builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-025 starttime: 1483068102.61 results: success (0) buildid: 20161229182419 builduid: 55410856d22443e5a334ea2074a9e4d7 revision: 6f63f95e28ffc05c0d2f5ef6cd6e05905fe8ea5a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-29 19:21:42.609036) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-29 19:21:42.609595) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-29 19:21:42.609896) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-29 19:21:42.625762) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 19:21:42.626274) ========= 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=232498196343f89f466a4a9a000001c8-1483068070.617726-110951684 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-29 19:21:43-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 89.3M=0s 2016-12-29 19:21:43 (89.3 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.091147 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 19:21:42.732676) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 19:21:42.733189) ========= 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=232498196343f89f466a4a9a000001c8-1483068070.617726-110951684 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013730 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 19:21:42.782994) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 19:21:42.783307) ========= 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=232498196343f89f466a4a9a000001c8-1483068070.617726-110951684 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-29 19:21:43,576 truncating revision to first 12 chars 2016-12-29 19:21:43,576 Setting DEBUG logging. 2016-12-29 19:21:43,576 attempt 1/10 2016-12-29 19:21:43,576 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-29 19:21:43,901 unpacking tar archive at: mozilla-central-6f63f95e28ff/testing/mozharness/ program finished with exit code 0 elapsedTime=0.655331 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 19:21:43.453759) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-29 19:21:43.454307) ========= script_repo_revision: 6f63f95e28ffc05c0d2f5ef6cd6e05905fe8ea5a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-29 19:21:43.455018) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-29 19:21:43.455480) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-29 19:21:43.467215) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2016-12-29 19:21:43.467717) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=232498196343f89f466a4a9a000001c8-1483068070.617726-110951684 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:21:44 INFO - MultiFileLogger online at 20161229 19:21:44 in /builds/slave/test 19:21:44 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 19:21:44 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:21:44 INFO - {'append_to_log': False, 19:21:44 INFO - 'base_work_dir': '/builds/slave/test', 19:21:44 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 19:21:44 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:21:44 INFO - 'branch': 'Firefox-Non-PGO', 19:21:44 INFO - 'buildbot_json_path': 'buildprops.json', 19:21:44 INFO - 'config_files': ('talos/linux_config.py',), 19:21:44 INFO - 'default_actions': ('clobber', 19:21:44 INFO - 'read-buildbot-config', 19:21:44 INFO - 'download-and-extract', 19:21:44 INFO - 'populate-webroot', 19:21:44 INFO - 'create-virtualenv', 19:21:44 INFO - 'install', 19:21:44 INFO - 'run-tests'), 19:21:44 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:21:44 INFO - 'download_minidump_stackwalk': True, 19:21:44 INFO - 'download_symbols': 'ondemand', 19:21:44 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:21:44 INFO - 'tooltool.py': '/tools/tooltool.py', 19:21:44 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:21:44 INFO - '/tools/misc-python/virtualenv.py')}, 19:21:44 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:21:44 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:21:44 INFO - 'installer_path': 'installer.exe', 19:21:44 INFO - 'log_level': 'info', 19:21:44 INFO - 'log_name': 'talos', 19:21:44 INFO - 'log_to_console': True, 19:21:44 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:21:44 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:21:44 INFO - 'opt_config_files': (), 19:21:44 INFO - 'pip_index': False, 19:21:44 INFO - 'sps_profile': False, 19:21:44 INFO - 'sps_profile_interval': 0, 19:21:44 INFO - 'suite': 'dromaeojs', 19:21:44 INFO - 'system_bits': '32', 19:21:44 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:21:44 INFO - 'title': 'talos-linux64-ix-025', 19:21:44 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:21:44 INFO - 'use_talos_json': True, 19:21:44 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:21:44 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:21:44 INFO - 'work_dir': 'build'} 19:21:44 INFO - [mozharness: 2016-12-30 03:21:44.359375Z] Running clobber step. 19:21:44 INFO - Running pre-action listener: _resource_record_pre_action 19:21:44 INFO - Running main action method: clobber 19:21:44 INFO - rmtree: /builds/slave/test/build 19:21:44 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:21:45 INFO - Running post-action listener: _resource_record_post_action 19:21:45 INFO - [mozharness: 2016-12-30 03:21:45.341944Z] Finished clobber step (success) 19:21:45 INFO - [mozharness: 2016-12-30 03:21:45.342044Z] Running read-buildbot-config step. 19:21:45 INFO - Running pre-action listener: _resource_record_pre_action 19:21:45 INFO - Running main action method: read_buildbot_config 19:21:45 INFO - Using buildbot properties: 19:21:45 INFO - { 19:21:45 INFO - "project": "", 19:21:45 INFO - "product": "firefox", 19:21:45 INFO - "script_repo_revision": "production", 19:21:45 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 19:21:45 INFO - "repository": "", 19:21:45 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 19:21:45 INFO - "buildid": "20161229182419", 19:21:45 INFO - "pgo_build": "False", 19:21:45 INFO - "basedir": "/builds/slave/test", 19:21:45 INFO - "buildnumber": 364, 19:21:45 INFO - "slavename": "talos-linux64-ix-025", 19:21:45 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 19:21:45 INFO - "platform": "ubuntu64_hw", 19:21:45 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 19:21:45 INFO - "branch": "mozilla-central", 19:21:45 INFO - "repo_path": "mozilla-central", 19:21:45 INFO - "revision": "6f63f95e28ffc05c0d2f5ef6cd6e05905fe8ea5a", 19:21:45 INFO - "stage_platform": "linux64", 19:21:45 INFO - "builduid": "55410856d22443e5a334ea2074a9e4d7", 19:21:45 INFO - "slavebuilddir": "test" 19:21:45 INFO - } 19:21:45 INFO - Found installer url https://queue.taskcluster.net/v1/task/bYYEqKOYTxqz2HOcFDTVZQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 19:21:45 INFO - Running post-action listener: _resource_record_post_action 19:21:45 INFO - [mozharness: 2016-12-30 03:21:45.344476Z] Finished read-buildbot-config step (success) 19:21:45 INFO - [mozharness: 2016-12-30 03:21:45.344565Z] Running download-and-extract step. 19:21:45 INFO - Running pre-action listener: _resource_record_pre_action 19:21:45 INFO - Running main action method: download_and_extract 19:21:45 INFO - mkdir: /builds/slave/test/build/tests 19:21:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:21:45 INFO - trying https://queue.taskcluster.net/v1/task/bYYEqKOYTxqz2HOcFDTVZQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 19:21:45 INFO - Downloading https://queue.taskcluster.net/v1/task/bYYEqKOYTxqz2HOcFDTVZQ/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 19:21:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bYYEqKOYTxqz2HOcFDTVZQ/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 19:21:45 INFO - Downloaded 1361 bytes. 19:21:45 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 19:21:45 INFO - Using the following test package requirements: 19:21:45 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 19:21:45 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 19:21:45 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 19:21:45 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 19:21:45 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 19:21:45 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 19:21:45 INFO - u'jsshell-linux-x86_64.zip'], 19:21:45 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 19:21:45 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 19:21:45 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 19:21:45 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 19:21:45 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 19:21:45 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 19:21:45 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 19:21:45 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 19:21:45 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 19:21:45 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 19:21:45 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 19:21:45 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 19:21:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bYYEqKOYTxqz2HOcFDTVZQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 19:21:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bYYEqKOYTxqz2HOcFDTVZQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 19:21:45 INFO - Fetch https://queue.taskcluster.net/v1/task/bYYEqKOYTxqz2HOcFDTVZQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 19:21:46 INFO - Expected file size: 20246099 19:21:46 INFO - Obtained file size: 20246099 19:21:50 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 19:21:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bYYEqKOYTxqz2HOcFDTVZQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 19:21:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bYYEqKOYTxqz2HOcFDTVZQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 19:21:50 INFO - Fetch https://queue.taskcluster.net/v1/task/bYYEqKOYTxqz2HOcFDTVZQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 19:21:51 INFO - Expected file size: 20246099 19:21:51 INFO - Obtained file size: 20246099 19:21:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bYYEqKOYTxqz2HOcFDTVZQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 19:21:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bYYEqKOYTxqz2HOcFDTVZQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 19:21:56 INFO - Fetch https://queue.taskcluster.net/v1/task/bYYEqKOYTxqz2HOcFDTVZQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 19:21:56 INFO - Expected file size: 14409776 19:21:56 INFO - Obtained file size: 14409776 19:21:57 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')]} 19:21:57 INFO - trying https://queue.taskcluster.net/v1/task/bYYEqKOYTxqz2HOcFDTVZQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 19:21:57 INFO - Downloading https://queue.taskcluster.net/v1/task/bYYEqKOYTxqz2HOcFDTVZQ/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 19:21:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bYYEqKOYTxqz2HOcFDTVZQ/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 19:21:59 INFO - Downloaded 55921866 bytes. 19:21:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bYYEqKOYTxqz2HOcFDTVZQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 19:21:59 INFO - mkdir: /builds/slave/test/properties 19:21:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:21:59 INFO - Writing to file /builds/slave/test/properties/build_url 19:21:59 INFO - Contents: 19:21:59 INFO - build_url:https://queue.taskcluster.net/v1/task/bYYEqKOYTxqz2HOcFDTVZQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 19:21:59 INFO - Running post-action listener: _resource_record_post_action 19:21:59 INFO - Running post-action listener: set_extra_try_arguments 19:21:59 INFO - [mozharness: 2016-12-30 03:21:59.986410Z] Finished download-and-extract step (success) 19:21:59 INFO - [mozharness: 2016-12-30 03:21:59.986637Z] Running populate-webroot step. 19:21:59 INFO - Running pre-action listener: _resource_record_pre_action 19:21:59 INFO - Running main action method: populate_webroot 19:22:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:22:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:22:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:22:00 INFO - '--tppagecycles', 19:22:00 INFO - '7'], 19:22:00 INFO - 'tests': ['tsvgm']}}, 19:22:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:22:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:22:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:22:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:22:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:22:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:22:00 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 19:22:00 INFO - 'talos_options': ['--disable-e10s'], 19:22:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:22:00 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 19:22:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:22:00 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 19:22:00 INFO - 'talos_options': ['--disable-e10s'], 19:22:00 INFO - 'tests': ['damp', 'tps']}, 19:22:00 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 19:22:00 INFO - 'tests': ['damp', 'tps']}, 19:22:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:22:00 INFO - 'tests': ['dromaeo_dom']}, 19:22:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:22:00 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:22:00 INFO - 'tests': ['basic_compositor_video']}, 19:22:00 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:22:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:22:00 INFO - 'tests': ['a11yr', 19:22:00 INFO - 'ts_paint', 19:22:00 INFO - 'tpaint', 19:22:00 INFO - 'sessionrestore', 19:22:00 INFO - 'sessionrestore_no_auto_restore', 19:22:00 INFO - 'tabpaint']}, 19:22:00 INFO - 'other-e10s': {'tests': ['a11yr', 19:22:00 INFO - 'ts_paint', 19:22:00 INFO - 'tpaint', 19:22:00 INFO - 'sessionrestore', 19:22:00 INFO - 'sessionrestore_no_auto_restore', 19:22:00 INFO - 'tabpaint']}, 19:22:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:22:00 INFO - 'ts_paint', 19:22:00 INFO - 'tpaint', 19:22:00 INFO - 'sessionrestore', 19:22:00 INFO - 'sessionrestore_no_auto_restore', 19:22:00 INFO - 'tabpaint']}, 19:22:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:22:00 INFO - 'ts_paint', 19:22:00 INFO - 'tpaint', 19:22:00 INFO - 'sessionrestore', 19:22:00 INFO - 'sessionrestore_no_auto_restore', 19:22:00 INFO - 'tabpaint']}, 19:22:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:22:00 INFO - 'tests': ['a11yr', 19:22:00 INFO - 'ts_paint', 19:22:00 INFO - 'tpaint', 19:22:00 INFO - 'sessionrestore', 19:22:00 INFO - 'sessionrestore_no_auto_restore', 19:22:00 INFO - 'tabpaint']}, 19:22:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:22:00 INFO - 'tests': ['a11yr', 19:22:00 INFO - 'ts_paint', 19:22:00 INFO - 'tpaint', 19:22:00 INFO - 'sessionrestore', 19:22:00 INFO - 'sessionrestore_no_auto_restore', 19:22:00 INFO - 'tabpaint']}, 19:22:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:22:00 INFO - 'tests': ['tsvgx', 19:22:00 INFO - 'tsvgr_opacity', 19:22:00 INFO - 'tart', 19:22:00 INFO - 'tscrollx', 19:22:00 INFO - 'cart', 19:22:00 INFO - 'tsvg_static']}, 19:22:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:22:00 INFO - 'tsvgr_opacity', 19:22:00 INFO - 'tart', 19:22:00 INFO - 'tscrollx', 19:22:00 INFO - 'cart', 19:22:00 INFO - 'tsvg_static']}, 19:22:00 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 19:22:00 INFO - 'talos_options': ['--disable-e10s'], 19:22:00 INFO - 'tests': ['tp5o']}, 19:22:00 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 19:22:00 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 19:22:00 INFO - 'talos_options': ['--disable-e10s', 19:22:00 INFO - '--xperf_path', 19:22:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:22:00 INFO - 'tests': ['tp5n']}, 19:22:00 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 19:22:00 INFO - 'talos_options': ['--xperf_path', 19:22:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:22:00 INFO - 'tests': ['tp5n']}}, 19:22:00 INFO - 'talos.zip': {'path': '', 19:22:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:22:00 INFO - Running post-action listener: _resource_record_post_action 19:22:00 INFO - [mozharness: 2016-12-30 03:22:00.008611Z] Finished populate-webroot step (success) 19:22:00 INFO - [mozharness: 2016-12-30 03:22:00.008701Z] Running create-virtualenv step. 19:22:00 INFO - Running pre-action listener: _resource_record_pre_action 19:22:00 INFO - Running main action method: create_virtualenv 19:22:00 INFO - Creating virtualenv /builds/slave/test/build/venv 19:22:00 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 19:22:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:22:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:22:00 INFO - Using real prefix '/usr' 19:22:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:22:00 INFO - Installing distribute.............................................................................................................................................................................................done. 19:22:02 INFO - Installing pip.................done. 19:22:02 INFO - Return code: 0 19:22:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:22:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:22:02 INFO - Reading from file tmpfile_stdout 19:22:02 INFO - Output received: 19:22:02 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) 19:22:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:22:02 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')]} 19:22:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:22:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22:02 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')]} 19:22:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:22:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22:02 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 0x16101f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1720a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1982630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a0b850>, '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 0x19806d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1980b60>, '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': '232498196343f89f466a4a9a000001c8-1483068070.617726-110951684', '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 19:22:02 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 19:22:02 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 19:22:02 INFO - Using env: {'DISPLAY': ':0', 19:22:02 INFO - 'HOME': '/home/cltbld', 19:22:02 INFO - 'LANG': 'en_US.UTF-8', 19:22:02 INFO - 'LANGUAGE': 'en_US:en', 19:22:02 INFO - 'LOGNAME': 'cltbld', 19:22:02 INFO - 'MAIL': '/var/mail/cltbld', 19:22:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:22:02 INFO - 'MOZ_NO_REMOTE': '1', 19:22:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:22:02 INFO - 'NO_EM_RESTART': '1', 19:22:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:22:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:22:02 INFO - 'PWD': '/builds/slave/test', 19:22:02 INFO - 'SHELL': '/bin/bash', 19:22:02 INFO - 'SHLVL': '1', 19:22:02 INFO - 'TERM': 'linux', 19:22:02 INFO - 'TMOUT': '86400', 19:22:02 INFO - 'USER': 'cltbld', 19:22:02 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1483068070.617726-110951684', 19:22:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:22:02 INFO - '_': '/tools/buildbot/bin/python'} 19:22:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:22:02 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 19:22:02 INFO - Cleaning up... 19:22:03 INFO - Return code: 0 19:22:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:22:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:22:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:22:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:22:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:22:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16101f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1720a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1982630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a0b850>, '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 0x19806d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1980b60>, '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': '232498196343f89f466a4a9a000001c8-1483068070.617726-110951684', '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 19:22:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 19:22:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 19:22:03 INFO - Using env: {'DISPLAY': ':0', 19:22:03 INFO - 'HOME': '/home/cltbld', 19:22:03 INFO - 'LANG': 'en_US.UTF-8', 19:22:03 INFO - 'LANGUAGE': 'en_US:en', 19:22:03 INFO - 'LOGNAME': 'cltbld', 19:22:03 INFO - 'MAIL': '/var/mail/cltbld', 19:22:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:22:03 INFO - 'MOZ_NO_REMOTE': '1', 19:22:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:22:03 INFO - 'NO_EM_RESTART': '1', 19:22:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:22:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:22:03 INFO - 'PWD': '/builds/slave/test', 19:22:03 INFO - 'SHELL': '/bin/bash', 19:22:03 INFO - 'SHLVL': '1', 19:22:03 INFO - 'TERM': 'linux', 19:22:03 INFO - 'TMOUT': '86400', 19:22:03 INFO - 'USER': 'cltbld', 19:22:03 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1483068070.617726-110951684', 19:22:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:22:03 INFO - '_': '/tools/buildbot/bin/python'} 19:22:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:22:03 INFO - Downloading/unpacking psutil>=3.1.1 19:22:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:22:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:22:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:22:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:22:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:22:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:22:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:22:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:22:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:22:05 INFO - Installing collected packages: psutil 19:22:05 INFO - Running setup.py install for psutil 19:22:05 INFO - building 'psutil._psutil_linux' extension 19:22:05 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 19:22:05 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 19:22:05 INFO - building 'psutil._psutil_posix' extension 19:22:05 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 19:22:05 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 19:22:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:22:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:22:05 INFO - Successfully installed psutil 19:22:05 INFO - Cleaning up... 19:22:05 INFO - Return code: 0 19:22:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:22:05 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')]} 19:22:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:22:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22:05 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')]} 19:22:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:22:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22:05 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 0x16101f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1720a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1982630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a0b850>, '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 0x19806d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1980b60>, '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': '232498196343f89f466a4a9a000001c8-1483068070.617726-110951684', '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 19:22:05 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 19:22:05 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 19:22:05 INFO - Using env: {'DISPLAY': ':0', 19:22:05 INFO - 'HOME': '/home/cltbld', 19:22:05 INFO - 'LANG': 'en_US.UTF-8', 19:22:05 INFO - 'LANGUAGE': 'en_US:en', 19:22:05 INFO - 'LOGNAME': 'cltbld', 19:22:05 INFO - 'MAIL': '/var/mail/cltbld', 19:22:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:22:05 INFO - 'MOZ_NO_REMOTE': '1', 19:22:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:22:05 INFO - 'NO_EM_RESTART': '1', 19:22:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:22:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:22:05 INFO - 'PWD': '/builds/slave/test', 19:22:05 INFO - 'SHELL': '/bin/bash', 19:22:05 INFO - 'SHLVL': '1', 19:22:05 INFO - 'TERM': 'linux', 19:22:05 INFO - 'TMOUT': '86400', 19:22:05 INFO - 'USER': 'cltbld', 19:22:05 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1483068070.617726-110951684', 19:22:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:22:05 INFO - '_': '/tools/buildbot/bin/python'} 19:22:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:22:06 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:22:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:22:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:22:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:22:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:22:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:22:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:22:08 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:22:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:22:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:22:08 INFO - Installing collected packages: mozsystemmonitor 19:22:08 INFO - Running setup.py install for mozsystemmonitor 19:22:08 INFO - Successfully installed mozsystemmonitor 19:22:08 INFO - Cleaning up... 19:22:08 INFO - Return code: 0 19:22:08 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:22:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:22:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:22:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:22:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:22:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16101f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1720a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1982630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a0b850>, '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 0x19806d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1980b60>, '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': '232498196343f89f466a4a9a000001c8-1483068070.617726-110951684', '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 19:22:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 19:22:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 19:22:08 INFO - Using env: {'DISPLAY': ':0', 19:22:08 INFO - 'HOME': '/home/cltbld', 19:22:08 INFO - 'LANG': 'en_US.UTF-8', 19:22:08 INFO - 'LANGUAGE': 'en_US:en', 19:22:08 INFO - 'LOGNAME': 'cltbld', 19:22:08 INFO - 'MAIL': '/var/mail/cltbld', 19:22:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:22:08 INFO - 'MOZ_NO_REMOTE': '1', 19:22:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:22:08 INFO - 'NO_EM_RESTART': '1', 19:22:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:22:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:22:08 INFO - 'PWD': '/builds/slave/test', 19:22:08 INFO - 'SHELL': '/bin/bash', 19:22:08 INFO - 'SHLVL': '1', 19:22:08 INFO - 'TERM': 'linux', 19:22:08 INFO - 'TMOUT': '86400', 19:22:08 INFO - 'USER': 'cltbld', 19:22:08 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1483068070.617726-110951684', 19:22:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:22:08 INFO - '_': '/tools/buildbot/bin/python'} 19:22:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:22:08 INFO - Downloading/unpacking jsonschema==2.5.1 19:22:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:22:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:22:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:22:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:22:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:22:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:22:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:22:10 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:22:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:22:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:22:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:22:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:22:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:22:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:22:10 INFO - Downloading functools32-3.2.3-2.tar.gz 19:22:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:22:10 INFO - warning: no files found matching '*.txt' 19:22:10 INFO - no previously-included directories found matching 'build' 19:22:10 INFO - no previously-included directories found matching 'dist' 19:22:10 INFO - no previously-included directories found matching '.git*' 19:22:10 INFO - Installing collected packages: jsonschema, functools32 19:22:10 INFO - Running setup.py install for functools32 19:22:10 INFO - warning: no files found matching '*.txt' 19:22:10 INFO - no previously-included directories found matching 'build' 19:22:10 INFO - no previously-included directories found matching 'dist' 19:22:10 INFO - no previously-included directories found matching '.git*' 19:22:10 INFO - Successfully installed jsonschema functools32 19:22:10 INFO - Cleaning up... 19:22:10 INFO - Return code: 0 19:22:11 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:22:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:22:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:22:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:22:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:22:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16101f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1720a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1982630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a0b850>, '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 0x19806d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1980b60>, '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': '232498196343f89f466a4a9a000001c8-1483068070.617726-110951684', '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 19:22:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 19:22:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 19:22:11 INFO - Using env: {'DISPLAY': ':0', 19:22:11 INFO - 'HOME': '/home/cltbld', 19:22:11 INFO - 'LANG': 'en_US.UTF-8', 19:22:11 INFO - 'LANGUAGE': 'en_US:en', 19:22:11 INFO - 'LOGNAME': 'cltbld', 19:22:11 INFO - 'MAIL': '/var/mail/cltbld', 19:22:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:22:11 INFO - 'MOZ_NO_REMOTE': '1', 19:22:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:22:11 INFO - 'NO_EM_RESTART': '1', 19:22:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:22:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:22:11 INFO - 'PWD': '/builds/slave/test', 19:22:11 INFO - 'SHELL': '/bin/bash', 19:22:11 INFO - 'SHLVL': '1', 19:22:11 INFO - 'TERM': 'linux', 19:22:11 INFO - 'TMOUT': '86400', 19:22:11 INFO - 'USER': 'cltbld', 19:22:11 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1483068070.617726-110951684', 19:22:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:22:11 INFO - '_': '/tools/buildbot/bin/python'} 19:22:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:22:11 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:22:11 INFO - Cleaning up... 19:22:11 INFO - Return code: 0 19:22:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:22:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:22:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:22:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:22:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:22:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16101f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1720a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1982630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a0b850>, '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 0x19806d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1980b60>, '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': '232498196343f89f466a4a9a000001c8-1483068070.617726-110951684', '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 19:22:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:22:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 19:22:11 INFO - Using env: {'DISPLAY': ':0', 19:22:11 INFO - 'HOME': '/home/cltbld', 19:22:11 INFO - 'LANG': 'en_US.UTF-8', 19:22:11 INFO - 'LANGUAGE': 'en_US:en', 19:22:11 INFO - 'LOGNAME': 'cltbld', 19:22:11 INFO - 'MAIL': '/var/mail/cltbld', 19:22:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:22:11 INFO - 'MOZ_NO_REMOTE': '1', 19:22:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:22:11 INFO - 'NO_EM_RESTART': '1', 19:22:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:22:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:22:11 INFO - 'PWD': '/builds/slave/test', 19:22:11 INFO - 'SHELL': '/bin/bash', 19:22:11 INFO - 'SHLVL': '1', 19:22:11 INFO - 'TERM': 'linux', 19:22:11 INFO - 'TMOUT': '86400', 19:22:11 INFO - 'USER': 'cltbld', 19:22:11 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1483068070.617726-110951684', 19:22:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:22:11 INFO - '_': '/tools/buildbot/bin/python'} 19:22:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:22:11 INFO - Downloading/unpacking blobuploader==1.2.4 19:22:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:22:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:22:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:22:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:22:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:22:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:22:13 INFO - Downloading blobuploader-1.2.4.tar.gz 19:22:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:22:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:22:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:22:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:22:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:22:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:22:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:22:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:22:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:22:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:22:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:22:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:22:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:22:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:22:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:22:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:22:14 INFO - Downloading docopt-0.6.1.tar.gz 19:22:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:22:14 INFO - Installing collected packages: blobuploader, requests, docopt 19:22:14 INFO - Running setup.py install for blobuploader 19:22:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:22:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:22:14 INFO - Running setup.py install for requests 19:22:14 INFO - Running setup.py install for docopt 19:22:14 INFO - Successfully installed blobuploader requests docopt 19:22:14 INFO - Cleaning up... 19:22:14 INFO - Return code: 0 19:22:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:22:14 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')]} 19:22:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:22:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22:14 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')]} 19:22:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:22:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22:14 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 0x16101f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1720a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1982630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a0b850>, '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 0x19806d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1980b60>, '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': '232498196343f89f466a4a9a000001c8-1483068070.617726-110951684', '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 19:22:14 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 19:22:14 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 19:22:14 INFO - Using env: {'DISPLAY': ':0', 19:22:14 INFO - 'HOME': '/home/cltbld', 19:22:14 INFO - 'LANG': 'en_US.UTF-8', 19:22:14 INFO - 'LANGUAGE': 'en_US:en', 19:22:14 INFO - 'LOGNAME': 'cltbld', 19:22:14 INFO - 'MAIL': '/var/mail/cltbld', 19:22:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:22:14 INFO - 'MOZ_NO_REMOTE': '1', 19:22:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:22:14 INFO - 'NO_EM_RESTART': '1', 19:22:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:22:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:22:14 INFO - 'PWD': '/builds/slave/test', 19:22:14 INFO - 'SHELL': '/bin/bash', 19:22:14 INFO - 'SHLVL': '1', 19:22:14 INFO - 'TERM': 'linux', 19:22:14 INFO - 'TMOUT': '86400', 19:22:14 INFO - 'USER': 'cltbld', 19:22:14 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1483068070.617726-110951684', 19:22:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:22:14 INFO - '_': '/tools/buildbot/bin/python'} 19:22:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:22:15 INFO - Running setup.py (path:/tmp/pip-GLO8m2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:22:15 INFO - Running setup.py (path:/tmp/pip-1rbZON-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:22:15 INFO - Running setup.py (path:/tmp/pip-X7W66s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:22:15 INFO - Running setup.py (path:/tmp/pip-Zq7RC3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:22:15 INFO - Running setup.py (path:/tmp/pip-IEjpfT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:22:15 INFO - Running setup.py (path:/tmp/pip-ojNzl4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:22:15 INFO - Running setup.py (path:/tmp/pip-BHbA1Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:22:15 INFO - Running setup.py (path:/tmp/pip-LBfsFD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:22:15 INFO - Running setup.py (path:/tmp/pip-Ok4fkw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:22:15 INFO - Running setup.py (path:/tmp/pip-ysymd1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:22:15 INFO - Running setup.py (path:/tmp/pip-pvv_qy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:22:15 INFO - Running setup.py (path:/tmp/pip-W9vhsC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:22:15 INFO - Running setup.py (path:/tmp/pip-ig_2Xp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:22:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:22:15 INFO - Running setup.py (path:/tmp/pip-KILucE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:22:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:22:16 INFO - Running setup.py (path:/tmp/pip-UVaTyf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:22:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:22:16 INFO - Running setup.py (path:/tmp/pip-RNqT66-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:22:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:22:16 INFO - Running setup.py (path:/tmp/pip-T2M8v6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:22:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:22:16 INFO - Running setup.py install for manifestparser 19:22:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:22:16 INFO - Running setup.py install for mozcrash 19:22:16 INFO - Running setup.py install for mozdebug 19:22:16 INFO - Running setup.py install for mozdevice 19:22:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:22:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:22:16 INFO - Running setup.py install for mozfile 19:22:16 INFO - Running setup.py install for mozhttpd 19:22:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:22:16 INFO - Running setup.py install for mozinfo 19:22:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:22:16 INFO - Running setup.py install for mozInstall 19:22:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:22:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:22:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:22:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:22:17 INFO - Running setup.py install for mozleak 19:22:17 INFO - Running setup.py install for mozlog 19:22:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:22:17 INFO - Running setup.py install for moznetwork 19:22:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:22:17 INFO - Running setup.py install for mozprocess 19:22:17 INFO - Running setup.py install for mozprofile 19:22:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:22:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:22:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:22:17 INFO - Running setup.py install for mozrunner 19:22:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:22:17 INFO - Running setup.py install for mozscreenshot 19:22:17 INFO - Running setup.py install for moztest 19:22:17 INFO - Running setup.py install for mozversion 19:22:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:22:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:22:18 INFO - Cleaning up... 19:22:18 INFO - Return code: 0 19:22:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:22:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:22:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:22:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:22:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:22:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16101f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1720a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1982630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a0b850>, '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 0x19806d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1980b60>, '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': '232498196343f89f466a4a9a000001c8-1483068070.617726-110951684', '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 19:22:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:22:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:22:18 INFO - Using env: {'DISPLAY': ':0', 19:22:18 INFO - 'HOME': '/home/cltbld', 19:22:18 INFO - 'LANG': 'en_US.UTF-8', 19:22:18 INFO - 'LANGUAGE': 'en_US:en', 19:22:18 INFO - 'LOGNAME': 'cltbld', 19:22:18 INFO - 'MAIL': '/var/mail/cltbld', 19:22:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:22:18 INFO - 'MOZ_NO_REMOTE': '1', 19:22:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:22:18 INFO - 'NO_EM_RESTART': '1', 19:22:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:22:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:22:18 INFO - 'PWD': '/builds/slave/test', 19:22:18 INFO - 'SHELL': '/bin/bash', 19:22:18 INFO - 'SHLVL': '1', 19:22:18 INFO - 'TERM': 'linux', 19:22:18 INFO - 'TMOUT': '86400', 19:22:18 INFO - 'USER': 'cltbld', 19:22:18 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1483068070.617726-110951684', 19:22:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:22:18 INFO - '_': '/tools/buildbot/bin/python'} 19:22:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:22:18 INFO - Running setup.py (path:/tmp/pip-tU4gvX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:22:18 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 19:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:22:18 INFO - Running setup.py (path:/tmp/pip-87W39h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:22:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:22:18 INFO - Running setup.py (path:/tmp/pip-fgHFw2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:22:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:22:18 INFO - Running setup.py (path:/tmp/pip-PbIOUu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:22:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:22:18 INFO - Running setup.py (path:/tmp/pip-owkk0B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:22:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 19:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:22:18 INFO - Running setup.py (path:/tmp/pip-d8ojsm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:22:18 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)) 19:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:22:18 INFO - Running setup.py (path:/tmp/pip-utibcR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:22:18 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)) 19:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:22:18 INFO - Running setup.py (path:/tmp/pip-NHhKco-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:22:18 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)) 19:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:22:18 INFO - Running setup.py (path:/tmp/pip-kbJ96_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:22:18 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)) 19:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:22:18 INFO - Running setup.py (path:/tmp/pip-pZpho3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:22:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:22:19 INFO - Running setup.py (path:/tmp/pip-d_NTyG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:22:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 19:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:22:19 INFO - Running setup.py (path:/tmp/pip-ZcJin7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:22:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 19:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:22:19 INFO - Running setup.py (path:/tmp/pip-nNQfoA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:22:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 19:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:22:19 INFO - Running setup.py (path:/tmp/pip-0A1Awz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:22:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 19:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:22:19 INFO - Running setup.py (path:/tmp/pip-vDM7Dt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:22:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 19:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:22:19 INFO - Running setup.py (path:/tmp/pip-VMOnp8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:22:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 19:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:22:19 INFO - Running setup.py (path:/tmp/pip-LmxTuI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:22:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 19:22:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:22:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:22:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:22:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:22:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:22:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:22:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:22:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:22:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:22:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:22:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:22:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:22:21 INFO - Downloading blessings-1.6.tar.gz 19:22:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:22:21 INFO - Installing collected packages: blessings 19:22:21 INFO - Running setup.py install for blessings 19:22:21 INFO - Successfully installed blessings 19:22:21 INFO - Cleaning up... 19:22:21 INFO - Return code: 0 19:22:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:22:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:22:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:22:21 INFO - Reading from file tmpfile_stdout 19:22:21 INFO - Current package versions: 19:22:21 INFO - argparse == 1.2.1 19:22:21 INFO - blessings == 1.6 19:22:21 INFO - blobuploader == 1.2.4 19:22:21 INFO - docopt == 0.6.1 19:22:21 INFO - functools32 == 3.2.3-2 19:22:21 INFO - jsonschema == 2.5.1 19:22:21 INFO - manifestparser == 1.1 19:22:21 INFO - mozInstall == 1.12 19:22:21 INFO - mozcrash == 1.0 19:22:21 INFO - mozdebug == 0.1 19:22:21 INFO - mozdevice == 0.49 19:22:21 INFO - mozfile == 1.2 19:22:21 INFO - mozhttpd == 0.7 19:22:21 INFO - mozinfo == 0.9 19:22:21 INFO - mozleak == 0.1 19:22:21 INFO - mozlog == 3.4 19:22:21 INFO - moznetwork == 0.27 19:22:21 INFO - mozprocess == 0.23 19:22:21 INFO - mozprofile == 0.28 19:22:21 INFO - mozrunner == 6.13 19:22:21 INFO - mozscreenshot == 0.1 19:22:21 INFO - mozsystemmonitor == 0.3 19:22:21 INFO - moztest == 0.8 19:22:21 INFO - mozversion == 1.4 19:22:21 INFO - psutil == 3.1.1 19:22:21 INFO - requests == 1.2.3 19:22:21 INFO - wsgiref == 0.1.2 19:22:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:22:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:22:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:22:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:22:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:22:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16101f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1720a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1982630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a0b850>, '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 0x19806d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1980b60>, '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': '232498196343f89f466a4a9a000001c8-1483068070.617726-110951684', '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 19:22:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 19:22:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:22:22 INFO - Using env: {'DISPLAY': ':0', 19:22:22 INFO - 'HOME': '/home/cltbld', 19:22:22 INFO - 'LANG': 'en_US.UTF-8', 19:22:22 INFO - 'LANGUAGE': 'en_US:en', 19:22:22 INFO - 'LOGNAME': 'cltbld', 19:22:22 INFO - 'MAIL': '/var/mail/cltbld', 19:22:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:22:22 INFO - 'MOZ_NO_REMOTE': '1', 19:22:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:22:22 INFO - 'NO_EM_RESTART': '1', 19:22:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:22:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:22:22 INFO - 'PWD': '/builds/slave/test', 19:22:22 INFO - 'SHELL': '/bin/bash', 19:22:22 INFO - 'SHLVL': '1', 19:22:22 INFO - 'TERM': 'linux', 19:22:22 INFO - 'TMOUT': '86400', 19:22:22 INFO - 'USER': 'cltbld', 19:22:22 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1483068070.617726-110951684', 19:22:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:22:22 INFO - '_': '/tools/buildbot/bin/python'} 19:22:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:22:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:22:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 19:22:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 19:22:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:22:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 19:22:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 19:22:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 19:22:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 19:22:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 19:22:22 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:22:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:22:22 INFO - Cleaning up... 19:22:22 INFO - Return code: 0 19:22:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:22:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:22:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:22:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:22:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:22:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:22:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16101f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1720a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1982630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a0b850>, '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 0x19806d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1980b60>, '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': '232498196343f89f466a4a9a000001c8-1483068070.617726-110951684', '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 19:22:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 19:22:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:22:22 INFO - Using env: {'DISPLAY': ':0', 19:22:22 INFO - 'HOME': '/home/cltbld', 19:22:22 INFO - 'LANG': 'en_US.UTF-8', 19:22:22 INFO - 'LANGUAGE': 'en_US:en', 19:22:22 INFO - 'LOGNAME': 'cltbld', 19:22:22 INFO - 'MAIL': '/var/mail/cltbld', 19:22:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:22:22 INFO - 'MOZ_NO_REMOTE': '1', 19:22:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:22:22 INFO - 'NO_EM_RESTART': '1', 19:22:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:22:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:22:22 INFO - 'PWD': '/builds/slave/test', 19:22:22 INFO - 'SHELL': '/bin/bash', 19:22:22 INFO - 'SHLVL': '1', 19:22:22 INFO - 'TERM': 'linux', 19:22:22 INFO - 'TMOUT': '86400', 19:22:22 INFO - 'USER': 'cltbld', 19:22:22 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1483068070.617726-110951684', 19:22:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:22:22 INFO - '_': '/tools/buildbot/bin/python'} 19:22:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:22:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:22:22 INFO - Cleaning up... 19:22:22 INFO - Return code: 0 19:22:22 INFO - Running post-action listener: _resource_record_post_action 19:22:22 INFO - Running post-action listener: _start_resource_monitoring 19:22:22 INFO - Starting resource monitoring. 19:22:22 INFO - [mozharness: 2016-12-30 03:22:22.494867Z] Finished create-virtualenv step (success) 19:22:22 INFO - [mozharness: 2016-12-30 03:22:22.495385Z] Running install step. 19:22:22 INFO - Running pre-action listener: _resource_record_pre_action 19:22:22 INFO - Running main action method: install 19:22:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:22:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:22:22 INFO - Reading from file tmpfile_stdout 19:22:22 INFO - Detecting whether we're running mozinstall >=1.0... 19:22:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:22:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:22:22 INFO - Reading from file tmpfile_stdout 19:22:22 INFO - Output received: 19:22:22 INFO - Usage: mozinstall [options] installer 19:22:22 INFO - Options: 19:22:22 INFO - -h, --help show this help message and exit 19:22:22 INFO - -d DEST, --destination=DEST 19:22:22 INFO - Directory to install application into. [default: 19:22:22 INFO - "/builds/slave/test"] 19:22:22 INFO - --app=APP Application being installed. [default: firefox] 19:22:22 INFO - mkdir: /builds/slave/test/build/application 19:22:22 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'] 19:22:22 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 19:22:39 INFO - Reading from file tmpfile_stdout 19:22:39 INFO - Output received: 19:22:39 INFO - /builds/slave/test/build/application/firefox/firefox 19:22:39 INFO - Running post-action listener: _resource_record_post_action 19:22:39 INFO - [mozharness: 2016-12-30 03:22:39.515852Z] Finished install step (success) 19:22:39 INFO - [mozharness: 2016-12-30 03:22:39.516096Z] Running run-tests step. 19:22:39 INFO - Running pre-action listener: _resource_record_pre_action 19:22:39 INFO - Running main action method: run_tests 19:22:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:22:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:22:39 INFO - Python 2.7.3 19:22:39 INFO - Return code: 0 19:22:39 INFO - grabbing minidump binary from tooltool 19:22:39 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')]} 19:22:39 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 0x1a0b850>, '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 0x19806d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1980b60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:22:39 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 19:22:39 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 19:22:39 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 19:22:39 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:22:39 INFO - Return code: 0 19:22:39 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:22:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:22:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:22:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:22:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:22:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bYYEqKOYTxqz2HOcFDTVZQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-025', '--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 19:22:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/bYYEqKOYTxqz2HOcFDTVZQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-025 --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 19:22:39 INFO - Using env: {'DISPLAY': ':0', 19:22:39 INFO - 'HOME': '/home/cltbld', 19:22:39 INFO - 'LANG': 'en_US.UTF-8', 19:22:39 INFO - 'LANGUAGE': 'en_US:en', 19:22:39 INFO - 'LOGNAME': 'cltbld', 19:22:39 INFO - 'MAIL': '/var/mail/cltbld', 19:22:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:22:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:22:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:22:39 INFO - 'MOZ_NO_REMOTE': '1', 19:22:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:22:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:22:39 INFO - 'NO_EM_RESTART': '1', 19:22:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:22:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:22:39 INFO - 'PWD': '/builds/slave/test', 19:22:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:22:39 INFO - 'SHELL': '/bin/bash', 19:22:39 INFO - 'SHLVL': '1', 19:22:39 INFO - 'TERM': 'linux', 19:22:39 INFO - 'TMOUT': '86400', 19:22:39 INFO - 'USER': 'cltbld', 19:22:39 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1483068070.617726-110951684', 19:22:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:22:39 INFO - '_': '/tools/buildbot/bin/python'} 19:22:39 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bYYEqKOYTxqz2HOcFDTVZQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-025', '--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 19:22:39 INFO - mozversion application_buildid: 20161229182419 19:22:39 INFO - mozversion application_changeset: 6f63f95e28ffc05c0d2f5ef6cd6e05905fe8ea5a 19:22:39 INFO - mozversion application_display_name: Nightly 19:22:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:22:39 INFO - mozversion application_name: Firefox 19:22:39 INFO - mozversion application_remotingname: firefox 19:22:39 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:22:39 INFO - mozversion application_vendor: Mozilla 19:22:39 INFO - mozversion application_version: 53.0a1 19:22:39 INFO - mozversion platform_buildid: 20161229182419 19:22:39 INFO - mozversion platform_changeset: 6f63f95e28ffc05c0d2f5ef6cd6e05905fe8ea5a 19:22:39 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:22:39 INFO - mozversion platform_version: 53.0a1 19:22:39 INFO - using testdate: 1483068159 19:22:39 INFO - actual date: 1483068159 19:22:39 INFO - starting webserver on 'localhost:36025' 19:22:39 INFO - SUITE-START | Running 2 tests 19:22:39 INFO - TEST-START | dromaeo_css 19:22:39 INFO - operating with platform_type : linux_ 19:22:39 INFO - Initialising browser for dromaeo_css test... 19:22:39 INFO - TEST-INFO | started process 4778 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOS7WSB/profile http://localhost:36025/getInfo.html) 19:22:40 INFO - PROCESS | 4778 | 1483068160334 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 19:22:43 INFO - PROCESS | 4778 | __metrics Screen width/height:1600/1200 19:22:43 INFO - PROCESS | 4778 | colorDepth:24 19:22:43 INFO - PROCESS | 4778 | Browser inner width/height: 1024/697 19:22:43 INFO - PROCESS | 4778 | __metrics 19:22:44 INFO - PROCESS | 4778 | 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" 19:22:44 INFO - PROCESS | 4778 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:22:45 INFO - TEST-INFO | 4778: exit 0 19:22:45 INFO - Browser initialized. 19:22:45 INFO - Running cycle 1/1 for dromaeo_css test... 19:22:45 INFO - TEST-INFO | started process 4861 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOS7WSB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:22:57 INFO - PROCESS | 4861 | RSS: Main: 151347200 19:22:57 INFO - PROCESS | 4861 | 19:26:01 INFO - PROCESS | 4861 | Cycle 1(1): loaded http://localhost:36025/tests/dromaeo/cssquery-dojo.html (next: http://localhost:36025/tests/dromaeo/cssquery-ext.html) 19:26:04 INFO - PROCESS | 4861 | RSS: Main: 368312320 19:26:04 INFO - PROCESS | 4861 | 19:27:46 INFO - PROCESS | 4861 | ************************* 19:27:46 INFO - PROCESS | 4861 | A coding exception was thrown and uncaught in a Task. 19:27:46 INFO - PROCESS | 4861 | 19:27:46 INFO - PROCESS | 4861 | Full message: ReferenceError: fetch is not defined 19:27:46 INFO - PROCESS | 4861 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 19:27:46 INFO - PROCESS | 4861 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 19:27:46 INFO - PROCESS | 4861 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 19:27:46 INFO - PROCESS | 4861 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 19:27:46 INFO - PROCESS | 4861 | TaskImpl@resource://gre/modules/Task.jsm:277:3 19:27:46 INFO - PROCESS | 4861 | asyncFunction@resource://gre/modules/Task.jsm:252:14 19:27:46 INFO - PROCESS | 4861 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 19:27:46 INFO - PROCESS | 4861 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 19:27:46 INFO - PROCESS | 4861 | TaskImpl@resource://gre/modules/Task.jsm:277:3 19:27:46 INFO - PROCESS | 4861 | asyncFunction@resource://gre/modules/Task.jsm:252:14 19:27:46 INFO - PROCESS | 4861 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 19:27:46 INFO - PROCESS | 4861 | 19:27:46 INFO - PROCESS | 4861 | ************************* 19:29:06 INFO - PROCESS | 4861 | Cycle 1(1): loaded http://localhost:36025/tests/dromaeo/cssquery-ext.html (next: http://localhost:36025/tests/dromaeo/cssquery-jquery.html) 19:29:07 INFO - PROCESS | 4861 | RSS: Main: 245825536 19:29:07 INFO - PROCESS | 4861 | 19:32:09 INFO - PROCESS | 4861 | Cycle 1(1): loaded http://localhost:36025/tests/dromaeo/cssquery-jquery.html (next: http://localhost:36025/tests/dromaeo/cssquery-mootools.html) 19:32:10 INFO - PROCESS | 4861 | RSS: Main: 185511936 19:32:10 INFO - PROCESS | 4861 | 19:35:12 INFO - PROCESS | 4861 | Cycle 1(1): loaded http://localhost:36025/tests/dromaeo/cssquery-mootools.html (next: http://localhost:36025/tests/dromaeo/cssquery-prototype.html) 19:35:13 INFO - PROCESS | 4861 | RSS: Main: 193310720 19:35:13 INFO - PROCESS | 4861 | 19:38:16 INFO - PROCESS | 4861 | Cycle 1(1): loaded http://localhost:36025/tests/dromaeo/cssquery-prototype.html (next: http://localhost:36025/tests/dromaeo/cssquery-yui.html) 19:38:17 INFO - PROCESS | 4861 | RSS: Main: 186187776 19:38:17 INFO - PROCESS | 4861 | 19:41:20 INFO - PROCESS | 4861 | Cycle 1(1): loaded http://localhost:36025/tests/dromaeo/cssquery-yui.html (next: http://localhost:36025/tests/dromaeo/cssquery-dojo.html) 19:41:20 INFO - PROCESS | 4861 | RSS: Main: 275460096 19:41:20 INFO - PROCESS | 4861 | 19:41:20 INFO - PROCESS | 4861 | __start_tp_report 19:41:20 INFO - PROCESS | 4861 | _x_x_mozilla_page_load 19:41:20 INFO - PROCESS | 4861 | _x_x_mozilla_page_load_details 19:41:20 INFO - PROCESS | 4861 | |i|pagename|runs| 19:41:20 INFO - PROCESS | 4861 | |0;dojo.html;2553.323369307134;2556.169244995377;2581.1998280533035;2600.492903883742;2609.2041927212194;4581.587657110859;4600.953990460102;4740.525947405258;4745.4305483342005;4747.145513807518;4675.088357770236;4782.952170478294;4808.975955120228;4857.538533839289;4888.07126645937;4416.97791511042;4461.196984542781;4484.798184081724;4495.258282383419;4537.954620453803;4511.977440112795;4518.593326600605;4530.411046563942;4539.092181563684;4558.202314594949;4341.3053911374345;4360.324149756792;4418.425604671388;4425.645948324126;4441.844535441261;4377.277749171383;4380.18966491197;4399.098184872126;4415.359772832953;4441.511433742286;4340.414044104069;4382.298832186867;4396.516383197845;4432.734035957853;4457.821687132511;4755.334253204554;4796.256580230035;4805.879853003668;4816.975915120437;4839.951600483985;5031.396232452093;5065.240213967897;5113.616478764107;5121.103806833827;5142.948570514322;5207.9479205207845;5212.400573934008;5216.9739151304;5238.05714971305;5257.053730328539;4836.177849765549;4853.927191092136;4855.198892182821;4861.635377346643;4905.484924082992;5071.974640126776;5111.616628752859;5123.948760512421;5300;5335.226392173113;4944.728039957837;5020;5020.447750747415;5042.722650254272;5083.542481176712;5056.797728090835;5096.108181068298;5126.743662816844;5134.306868572769;5138.614603904647;5028.899422011539;5105.719185444762;5114.130597798383;5125.15434953236;5148.794048238104;5161.045206636784;5167.069927413027;5168.819091331786;5171.146760784503;5182.689038657617;4253.766042867672;4280.336547835091;4323.113761678848;4342.717723347972;4457.175422546774;3781.4327850822597;3787.337215987191;3795.848166073326;3801.258754542893;3824.8470061197795;4379.64962802975;4383.386325914311;4383.9342409863875;4392.978035109868;4421.46942366918;5048.899022019613;5070.645054846125;5075.416327122354;5149.0474262261605;5202.609804264695;4346.087321662423;4347.108842687241;4367.934480982788;4396.934045989313;4406.206882761134;4622.930656040162;4631.235846085426;4641.164590373698;4670.136024835417;4670.462896766848;4717.622590192845;4746.145693775154;4787.736674482798;4790.616750659939;4794.928076078932;5105.4639262878345;5118.155504341742;5135.409427915837;5135.589152867837;5148.227765835155;4785.042991401664;4808.591269742111;4827.879303017453;4828.444728856227;4830.7101573905675;5061.139606267017;5074.416442109205;5075.086484432837;5075.416327122428;5077.619178561548;5150.381954165374;5159.045576568347;5159.793608255778;5164.741762911764;5175.37895452533;4975.253711943237;5002.174641184164;5010.298558201782;5023.572996295356;5024.397072351195;4956.851294461172;5018.949810501995;5025.070361983045;5025.547700706954;5034.119029169954;4889.095517329305;4916.655834091579;4938.456769755396;4960.851174464772;4991.550760431579;5006.824761133343;5010.649254552146;5011.373578302712;5015.448300686849;5057.190849464068;4818.783154757974;4825.30033145199;4871.707697538159;4892.2661600760175;4896.6572339935865 19:41:20 INFO - PROCESS | 4861 | |1;ext.html;520.0716720653634;536.8979893820174;545.9044667183242;548.5584104795638;559.2254727202826;36862;38856.22287554247;39026.60973390265;42633;42651;39047.21905561894;39461.278114673354;39513;39534.40698389519;39568.60431395685;7375.815604609901;7524.247575242473;7543.886841697379;7695.268949449794;7697.191794861543;9795.804083918318;10104.242181836355;10110.44392558409;10395.84406233907;10404;9933.106020458157;10001.89998100017;10011.699649010505;10013.349132306414;10014.399136051823;9709.951450242774;9730.513474326292;9735.17251033663;9739.513024348755;9746.853797193047;9914.9504252479;9954.253430992632;9973.85039224405;9976.301658883882;9977.850332245021;36488;36790.26419471623;36829.07927301812;36835.815820920994;36918.815405922796;3558.6085530591613;3597.334493118781;3615.1142969972525;3615.204654975901;3654.214343916071;27062.023177450465;27409.725902740916;27849.58225626617;28113.718862811314;28114.297142571395;3363.983180084084;3389.8983030509125;3493.7205023598053;3552.8401221945073;3563.376409128392;232589;234623;235825;236084;236375.81812091;49588.752056239486;49862;49931.25103123456;49944.50055499398;49970.50029499731;50987.7450612752;51008.74495627498;51087;51105.233421498706;51124.74437627863;13537.59387218385;13895.861041389659;13907.79138312926;13916.930415347859;13918.234497102756;9798.363106398061;10042.347247428894;10051.095401413908;10298.588056477656;10836.29564078348;13774.380152893144;14401.135931844123;14650.413983440756;14820.3330850112;15173;5964.850878728066;5979.19280897082;6040.275166979991;6114.691458015536;6180.4437600615265;736.2306389822648;739.4638886807129;747.8466914282668;755.3692666623322;763.8510490454308;25063.498730025298;25835.741642583333;25904.611430828554;26383;26435.206943791716;904.7783293093235;913.1325241021057;925.3476299209042;931.4550987672287;1057.9894201058046;13892.930535347461;13966.092204006707;14173.71652566943;14326.641833954234;14457.92771036138;330693;331836;332798.7201279853;333119;333388;46470.30294545517;46504.7674761624;46563.76718116388;46672.5332746675;46704.532954670016;1127.5715228213226;1141.3437273567731;1157.5080590748898;1159.7796418680423;1162.4594563528;25067;25735;26011.219663410127;26369;26447.206583802516;39159.80420097881;39626.20747584975;39689.20621587495;39708.404373934995;39751.204975900895;269526;269907;270831;271108;271170;33216.83391583027;33767.83116084404;33895.83052084724;34151.31697366088;34165;37928.62071379251;38382.80808595939;38965.41551876668;39375.2124957505;39428.80285598554;32423.189420264684;32739.836300818344;32951;33206.16984575308;33458.66541334555;32602.673973260913;32755;33069.66930330762;33486.497702534965;34118.31763364669;32570.51144232791;32775.836120819244;32778.344433111684;32982;33033.83483082569;25636.743632564183;27421.58867617027;29243.41513169767;29449.263768405963;32358.83820580882;22672;22740.09039638462;22778.886105569367;22904;22950.885245573667 19:41:20 INFO - PROCESS | 4861 | |2;jquery.html;350.98420571074297;354.14827340246717;372.2424865398913;372.4450568652708;388.6269181585678;13105.410256538455;13379.531716389929;13969.371378287977;16192.190390480488;16864.83135168648;16126.435574754885;16159.434419795309;16453.67092658146;16572.08853513059;16574.83976121672;4730.337752714614;5105.566026887719;5128.564072053861;5136.280920671109;5136.922946155811;3866.3620502617173;4062.6343629073376;5033.395992480889;5115.386153661547;5123.257127716479;5620.381758006616;5900.321463031742;5913.970430147843;5980.222571065756;5981.88036239275;5760.596758226926;5904.822855314347;5938.970305148489;5950.077737950624;5967.04527275636;5215.869603259911;5229.424763276036;5230.8692282692855;5231.555317797992;5232.686038837682;18911.90544047285;19453.9027304864;19522.609547809105;19525;19528.414147575597;2479.578471659804;2521.0924067335745;2530.72162062173;2563.090103013422;2599.714031456538;12087.576934807328;12118.757624847542;12310.384480776014;12708;12765.36173191346;1671.9498415047567;1734.2976094681674;1749.9037552934583;1767.805541390446;1772.0519522055638;59239.40760592382;59490.40509594892;59510.40489595049;59548.702256488876;59928.40071599272;9063.365564410427;9289.535523223813;9356.391834530734;9471.479068651153;9498.762530936781;12956.546520871725;12960.675983100498;12974.156679815782;12982;12998.545050923172;6724.058631791553;6842.247352791189;6971.442284617218;7019.543729657556;7054.788356349318;6436.613803171825;6529.477641788645;6647.900281495781;6664.86670266592;6673.733050677918;5834.795782147605;5850.5189412299615;5851;5961.493273071837;6064.272287325549;1691.3910992042854;1762.118940529735;1765.9470215893543;1791.5073354827528;1823.7902641196167;751.8007727952097;768.9692412303446;770.4667711254563;784.4704824243613;788.3456730913329;14812.925935370255;15059.02116362433;15666.764998525032;15787.842121578868;16468.917655411646;783.5024759277786;785.9331956783737;788.5347644889542;806.8426656801868;811.0146172400562;8961.372703910794;9011.549422528848;9199.264058875275;9418.29362797793;9433.633343460997;320625.50930115004;321151;323229;323604;325915;60783.39216607777;62027.379726203064;62435.37564624386;62578.687106565085;63559.364406355344;904.0552622509625;912.3020889019905;938.9295802814817;939.9895112753817;944.2021491839384;13723.519676811264;13838.23889686077;13912.078463522774;13920.234387108807;14481.275936202937;31768;32081.67918320787;32153.678463215067;32675;33720.32559348848;39667.404988925766;40670;41112.79443602763;41387;42912.78543607387;6351.491880649629;6446.194225721785;6668.766593169215;7198.49610527258;7617.809554761176;7068.611226382598;7369.742059027908;11001.094611412833;11885.940570297093;12272.140950133406;9408.81182376335;9626.133647971716;9691.079347462262;9775.21798256152;9904;10149.34029288094;10311.329763565343;10402.73993150147;10584.682459526226;10756.354618722902;9544.618215271312;9545.37728586156;9727.805443891224;10016.499175041365;10136.239782016231;6810.250872403933;6919.481038922;7037.1555413350725;7051.964740176266;7141.928580714334;9368;9689.079537443931;9796.216302695624;9937.055979681918;10069.194464442973 19:41:20 INFO - PROCESS | 4861 | |3;mootools.html;1289.6904742861716;1299.9935000324997;1302.5180683147237;1305.4060402516852;1308.2150709574257;6592.736290548379;6943.548669336502;6945.09713737214;6948.339907708772;6954.791356259314;6201.565890387675;6420.325865784095;6935.237123916378;7161.355478006978;7164.068671072753;1991.1338567723014;2028.3712049264702;2029.6752519596866;2030.9187632494682;2032.9898350508226;1763.841254287114;2031;2059.794020597943;2070.33749200256;2074.6680531115026;1796.2814874050418;1939.864209505335;1996.4210378990076;1997.3308941504613;2012.476756043432;1909.2840184930512;1919.443361425192;1938.0697265312658;1938.5250613599617;1941.2817257614772;1945.805419458057;1986.0764744393782;2012.5169959209832;2043.3767700851236;2052.127845665601;7237.819054523627;7298.598577078258;7320.890186647204;7321.37768289696;7322.5240359377;4217.135487225142;4359.215341238576;4361.389405483234;4363.170997510463;4365.061511774984;2590.248827839924;2693.5151672698908;2799.482095812261;2803.4533266013136;2808.578713193017;946.8081391578473;1018.7758693087509;1020.8264595018791;1023.5598692562195;1070.9089727373182;9545.093216144454;9570.665026724031;9571.617135314578;9574.712758617183;9575.61697532098;3557.911052223715;3839.9040023999623;3852.4799152114665;3882.3594106972596;3886.7473614214987;8396.580170991549;8399.790005249917;8405.327573794082;8412.453190542717;8417.284530814826;4151.6886233532605;4202.012527056139;4211.494620645542;4218.873433796991;4265.680073994463;3773.358529050013;3835.328817456934;3836.8848934532007;3838.3091043612417;3840.8271627776817;3428.400029994741;3428.434308339146;3440.1055725511546;3503.1417302760988;3504.141485336058;1098.5001824170142;1122.180808010157;1129.3675541696675;1133.1897693149438;1142.9428528573537;562.2406330412991;597.8953683105526;626.264333252153;635.9236891573041;643.5141468191486;4470.552944705503;4522.932156017662;4583.404157459576;4641.257398816173;4759.428868535798;801.9799505012421;808.1474044882705;809.9514029158152;812.5165526511717;818.8853560501534;3114.143610507129;3128.765342599314;3153.8738450462183;3159.6524382317925;3265.5264986576813;8160.918390816016;8673.783155421164;8674.175953284428;8681.956590217134;8683.56582170889;8568.143185681382;8624.310055195569;8624.439411438236;8632.697855575025;8651.870221946676;6552.148220731275;6715.7985260442265;6717.361850624183;6723.865522689519;6728.697208625624;4394.604485596312;4575.7483338416705;4576.839810606612;4587.220172570737;4628.907421851612;7690.577018264049;7851.489653172525;7853.607319634109;7906.209379062047;7955.1249362571225;7930.56381898978;7990.800229994296;8055.879161812694;8061.798455038671;8104.230098140667;5955.225820643289;6005.429484198994;6034.125051867542;6044.274687037584;6074.36219196978;6669;6760.932390676227;6760.966195168992;6762.459003279824;6762.763303284361;6125.877482450415;6269.529785266032;6296.464800492009;6316.84207894788;6437.871242575028;6189.319174890848;6233.75064997395;6293.339199383999;6297.275813281532;6297.685115744286;5370.758315875717;5492.038893193595;5496.285482887199;5499.450054994469;5501.889962200814;4007.1184339445276;4033.5361433435532;4038.8384464621085;4084.550699423002;4157.355609880473;3998.760074395429;4001.8199181037508;4008.8396464141106;4046.575109613566;4081.7959102044183 19:41:20 INFO - PROCESS | 4861 | |4;prototype.html;353.6622525488159;353.7346989757682;356.8340721564473;363.42034455044194;369.47718977646633;4672.859814205571;4783.760811959405;4838;4878.341423907771;4913.975430122849;4636.443626764784;4756.2865570164495;4787.677976181549;4793.233082706767;4810.618743721854;3828.636279553438;3837.57786643469;3848.326542855003;3849.7305188636806;3852.1717830666403;3640.690541303979;3714.331420344337;3880.7477513961644;3883.26218018577;3892.785896775676;3871.5741268460447;3878.3600705883496;3885.5920128386397;3886.1839013807135;3899.844006239747;3876.7867767272787;3880.185161116169;3884.184321292532;3886.6113388660906;3886.7862267575415;3495.6504349565093;3497.8251087445524;3506.3513250048823;3507.1232191952013;3514.279572687593;4565.885852853672;4645.7909394077005;4669.88325291867;4691.859244222679;4712.622990160813;4419.491758447755;4421.9115617687785;4425.867223983253;4430.4240448741775;4452.732836029849;5001.874953126165;5021.497850214986;5047.722375269359;5048.495150484959;5050.7222102784335;3705.7961812100325;3757.2528774120165;3772.2270532849516;3817.141143242776;3832.386818109117;5173.741312934339;5218.478152184751;5228.633995620308;5232.816851410221;5241.872997305598;3906.453096566483;3907.394353875097;3915.295246855593;3915.375119325505;3923.6076392360537;3907.5737355864903;3987.1626958338493;3998.28030954431;4022.456968309241;4025.19496100781;4393.428854248928;4393.890152746143;4409.977950110229;4417.867463976086;4443.133588950123;5032.421271553745;5046.6719663221775;5047.924281135786;5063.544281014652;5106.821261255838;1274.6877015131167;1298.9220646761225;1307.1764788183573;1352.7362164377853;1359.2592037339673;934.5934518484481;955.254901177083;968.4504792332212;991.6628346362127;992.3202606214766;4004.8998775030273;4270;4279.379489973936;4295.52749197588;4307.135754723136;4925.852224433273;4938.185199442124;4951.628627852925;4955.405351357788;4969.701817890938;4205.2640787862;4230.598093181143;4259.403683484315;4261.595148460891;4266.701330906807;2502.4995000999875;2612.7197673140404;2639.6832380114124;2648.4241876083697;2658.388570628766;4624.491305956342;4636.907261854812;4650.511696271843;4668.533146685304;4670.416197975253;5059;5072.503611434636;5082.415522214918;5092.65044763138;5101.923471147936;4482.955170448384;4611.054733779567;4648.674592778473;4655.324083329999;4664.277037059262;4930.422264875206;4950.504949505021;4957.578605818546;4958.471179698452;4985.551300382983;4873.634477414279;4912.5786791887585;4937.679050861682;4951.3810773653295;4967.9006419870675;4429.402030725811;4671.365022242188;4672.345871577913;4690.390249267573;4729.3378926950945;3738.401855703183;3740.775553466801;3756.86850960226;3768.265188288367;3774.226283611798;4907.926381104215;4910.263460480956;4911.4106307243355;4978.303037574815;5015.473375295542;4812.422509298906;4827.782749776198;4829.227323628203;4862.367892173995;4881.414230292387;4442.733820861168;4865.6107511398295;4913.6806107604325;5043.36957880265;5043.798248070036;4966.9817687374725;4994.077280247058;4997.625178111583;4997.775100120431;4999.100161970735;4876.195427754523;4976.576990955809;4991.251312303184;5003.824866129668;5042.319286896369;4875.1712208923855;4905.705657660552;4912.484189160086;4947.332110165082;4952.158133117451 19:41:20 INFO - PROCESS | 4861 | |5;yui.html;661.2230629010911;669.9430548403385;730.853829234153;751.8120469882529;773.2692605487814;12362.320072396025;12905.225686458807;12912.74174516509;12946.741065178703;12991.02567307451;12979.415926283316;13077.34613269335;13104.148230365043;13113.213207207551;13217.735645287088;658.3778329478656;686.1491750229704;797.5254723439573;814.9266566009057;824.5588610093602;831.8960129983752;833.679033572076;834.5159807311775;834.8121672623641;835.0522157351389;876.5354362188019;883.244825674049;883.3286702106413;883.3728053082319;884.5842454046619;700.2787129256874;721.07702141259;833.791552111972;859.8581234096366;864.5072308783996;637.1780403279763;637.5855693799068;638.6710843915388;638.8307098618869;639.229728177545;12530.310832904186;12894.548690795777;12938.48246070156;12963.546275880393;12977.286249256287;12710.237385756884;12839.165454245538;12927.741445171045;12955.611331660066;12958.352082395842;11657.883421165849;11715.297082175011;11806.822897656457;11998;12003.279803211748;4112.568180341081;4136.875893723193;4137.379393091031;4172.499300083979;4173.707840451169;59012;60043;60757.088643670824;61510.07734883935;62312.37687623111;10025.899741002642;10106.949465252774;10222.744431389276;10273.743156421;10452.111570516592;19011.714824277646;19019.809801901803;19034.52413689669;19034.904825475784;19048.14283357252;6469.320721324288;6742.224644165885;6806.251312355637;6812.318768123148;6817.5227734059135;3631;3640.2537479816574;3661;3767.9261410497875;3782.6406491383273;2679.852608106573;2744.423671029066;2749.3676454415595;2771.390294135287;2772.8059035867295;464.9814007439732;467.85730352242894;471.04613158354607;475.9714417134983;480.41149591749826;467.60720994364897;468.75624675168723;472.52511226217433;552.2241251042292;590.3034419385167;7463.776086717407;7991.640376183086;8144.267015968591;8144.429889907649;8575.085536702327;462.1288870479171;466.82260740918235;481.71097341594765;538.3943064052941;577.3187638586509;3920.7059470539825;4129.566395528426;4172.520160181557;4182.1844740275965;4241.94424104762;47619.285710714365;62082.41258286776;63493.36506634967;64653;65376.34623653703;46136.76931615388;53397.46602534003;55442.624098228414;55796.16305755417;56049;44489.33266001013;53197;55972.440275598354;56368.43631563714;57531;8735.30117590604;9339.133791629069;9488.288378371788;9511.528292173445;9524.666636667684;35831.82084089563;35859.82070089633;37138.44292335563;37572.812135939144;37925.24149517049;43605;43648;45032.324515132954;46793.76603116962;47342.28986565136;11703.01846166111;11843.98652229106;11935.701607459536;12170.14808963364;12253;11975.580854670045;12344.259344439362;12724.618261452171;12892.613221603367;13222.537211197561;11777;12047.698807529881;12168.391580421121;12222.144449888778;12735.426905789076;11578.421078946187;11738.70653233676;11884.227525210834;12245.507045966691;12310.876891230973;12102.75794484123;12454.501819927102;12516.87483125157;12975.480980761042;13121.475140994253;10478.685639430829;10539.578416863545;10788.514516846604;10990.120790336594;11271.830922536004;9327.906720932704;9751.268654850772;9797.706068817946;10013;10398.116160126172 19:41:20 INFO - PROCESS | 4861 | __end_tp_report 19:41:20 INFO - PROCESS | 4861 | __start_cc_report 19:41:20 INFO - PROCESS | 4861 | _x_x_mozilla_cycle_collect,2466 19:41:20 INFO - PROCESS | 4861 | __end_cc_report 19:41:20 INFO - PROCESS | 4861 | __startTimestamp1483069280421__endTimestamp 19:41:20 INFO - PROCESS | 4861 | 19:41:20 INFO - PROCESS | 4861 | ------- Summary: start ------- 19:41:20 INFO - PROCESS | 4861 | Number of tests: 6 19:41:20 INFO - PROCESS | 4861 | 19:41:20 INFO - PROCESS | 4861 | [#0] dojo.html Cycles:165 Average:4736.34 Median:4855.20 stddev:508.39 (10.5%) stddev-sans-first:480.24 19:41:20 INFO - PROCESS | 4861 | Values: 2553.3 2556.2 2581.2 2600.5 2609.2 4581.6 4601.0 4740.5 4745.4 4747.1 4675.1 4783.0 4809.0 4857.5 4888.1 4417.0 4461.2 4484.8 4495.3 4538.0 4512.0 4518.6 4530.4 4539.1 4558.2 4341.3 4360.3 4418.4 4425.6 4441.8 4377.3 4380.2 4399.1 4415.4 4441.5 4340.4 4382.3 4396.5 4432.7 4457.8 4755.3 4796.3 4805.9 4817.0 4840.0 5031.4 5065.2 5113.6 5121.1 5142.9 5207.9 5212.4 5217.0 5238.1 5257.1 4836.2 4853.9 4855.2 4861.6 4905.5 5072.0 5111.6 5123.9 5300.0 5335.2 4944.7 5020.0 5020.4 5042.7 5083.5 5056.8 5096.1 5126.7 5134.3 5138.6 5028.9 5105.7 5114.1 5125.2 5148.8 5161.0 5167.1 5168.8 5171.1 5182.7 4253.8 4280.3 4323.1 4342.7 4457.2 3781.4 3787.3 3795.8 3801.3 3824.8 4379.6 4383.4 4383.9 4393.0 4421.5 5048.9 5070.6 5075.4 5149.0 5202.6 4346.1 4347.1 4367.9 4396.9 4406.2 4622.9 4631.2 4641.2 4670.1 4670.5 4717.6 4746.1 4787.7 4790.6 4794.9 5105.5 5118.2 5135.4 5135.6 5148.2 4785.0 4808.6 4827.9 4828.4 4830.7 5061.1 5074.4 5075.1 5075.4 5077.6 5150.4 5159.0 5159.8 5164.7 5175.4 4975.3 5002.2 5010.3 5023.6 5024.4 4956.9 5018.9 5025.1 5025.5 5034.1 4889.1 4916.7 4938.5 4960.9 4991.6 5006.8 5010.6 5011.4 5015.4 5057.2 4818.8 4825.3 4871.7 4892.3 4896.7 19:41:20 INFO - PROCESS | 4861 | 19:41:20 INFO - PROCESS | 4861 | [#1] ext.html Cycles:180 Average:43355.97 Median:18642.86 stddev:73842.21 (396.1%) stddev-sans-first:73979.31 19:41:20 INFO - PROCESS | 4861 | Values: 520.1 536.9 545.9 548.6 559.2 36862.0 38856.2 39026.6 42633.0 42651.0 39047.2 39461.3 39513.0 39534.4 39568.6 7375.8 7524.2 7543.9 7695.3 7697.2 9795.8 10104.2 10110.4 10395.8 10404.0 9933.1 10001.9 10011.7 10013.3 10014.4 9710.0 9730.5 9735.2 9739.5 9746.9 9915.0 9954.3 9973.9 9976.3 9977.9 36488.0 36790.3 36829.1 36835.8 36918.8 3558.6 3597.3 3615.1 3615.2 3654.2 27062.0 27409.7 27849.6 28113.7 28114.3 3364.0 3389.9 3493.7 3552.8 3563.4 232589.0 234623.0 235825.0 236084.0 236375.8 49588.8 49862.0 49931.3 49944.5 49970.5 50987.7 51008.7 51087.0 51105.2 51124.7 13537.6 13895.9 13907.8 13916.9 13918.2 9798.4 10042.3 10051.1 10298.6 10836.3 13774.4 14401.1 14650.4 14820.3 15173.0 5964.9 5979.2 6040.3 6114.7 6180.4 736.2 739.5 747.8 755.4 763.9 25063.5 25835.7 25904.6 26383.0 26435.2 904.8 913.1 925.3 931.5 1058.0 13892.9 13966.1 14173.7 14326.6 14457.9 330693.0 331836.0 332798.7 333119.0 333388.0 46470.3 46504.8 46563.8 46672.5 46704.5 1127.6 1141.3 1157.5 1159.8 1162.5 25067.0 25735.0 26011.2 26369.0 26447.2 39159.8 39626.2 39689.2 39708.4 39751.2 269526.0 269907.0 270831.0 271108.0 271170.0 33216.8 33767.8 33895.8 34151.3 34165.0 37928.6 38382.8 38965.4 39375.2 39428.8 32423.2 32739.8 32951.0 33206.2 33458.7 32602.7 32755.0 33069.7 33486.5 34118.3 32570.5 32775.8 32778.3 32982.0 33033.8 25636.7 27421.6 29243.4 29449.3 32358.8 22672.0 22740.1 22778.9 22904.0 22950.9 19:41:20 INFO - PROCESS | 4861 | 19:41:20 INFO - PROCESS | 4861 | [#2] jquery.html Cycles:180 Average:21132.21 Median:5176.40 stddev:53126.54 (1026.3%) stddev-sans-first:53252.66 19:41:20 INFO - PROCESS | 4861 | Values: 351.0 354.1 372.2 372.4 388.6 13105.4 13379.5 13969.4 16192.2 16864.8 16126.4 16159.4 16453.7 16572.1 16574.8 4730.3 5105.6 5128.6 5136.3 5136.9 3866.4 4062.6 5033.4 5115.4 5123.3 5620.4 5900.3 5914.0 5980.2 5981.9 5760.6 5904.8 5939.0 5950.1 5967.0 5215.9 5229.4 5230.9 5231.6 5232.7 18911.9 19453.9 19522.6 19525.0 19528.4 2479.6 2521.1 2530.7 2563.1 2599.7 12087.6 12118.8 12310.4 12708.0 12765.4 1671.9 1734.3 1749.9 1767.8 1772.1 59239.4 59490.4 59510.4 59548.7 59928.4 9063.4 9289.5 9356.4 9471.5 9498.8 12956.5 12960.7 12974.2 12982.0 12998.5 6724.1 6842.2 6971.4 7019.5 7054.8 6436.6 6529.5 6647.9 6664.9 6673.7 5834.8 5850.5 5851.0 5961.5 6064.3 1691.4 1762.1 1765.9 1791.5 1823.8 751.8 769.0 770.5 784.5 788.3 14812.9 15059.0 15666.8 15787.8 16468.9 783.5 785.9 788.5 806.8 811.0 8961.4 9011.5 9199.3 9418.3 9433.6 320625.5 321151.0 323229.0 323604.0 325915.0 60783.4 62027.4 62435.4 62578.7 63559.4 904.1 912.3 938.9 940.0 944.2 13723.5 13838.2 13912.1 13920.2 14481.3 31768.0 32081.7 32153.7 32675.0 33720.3 39667.4 40670.0 41112.8 41387.0 42912.8 6351.5 6446.2 6668.8 7198.5 7617.8 7068.6 7369.7 11001.1 11885.9 12272.1 9408.8 9626.1 9691.1 9775.2 9904.0 10149.3 10311.3 10402.7 10584.7 10756.4 9544.6 9545.4 9727.8 10016.5 10136.2 6810.3 6919.5 7037.2 7052.0 7141.9 9368.0 9689.1 9796.2 9937.1 10069.2 19:41:20 INFO - PROCESS | 4861 | 19:41:20 INFO - PROCESS | 4861 | [#3] mootools.html Cycles:180 Average:4639.74 Median:4608.06 stddev:2575.86 (55.9%) stddev-sans-first:2570.78 19:41:20 INFO - PROCESS | 4861 | Values: 1289.7 1300.0 1302.5 1305.4 1308.2 6592.7 6943.5 6945.1 6948.3 6954.8 6201.6 6420.3 6935.2 7161.4 7164.1 1991.1 2028.4 2029.7 2030.9 2033.0 1763.8 2031.0 2059.8 2070.3 2074.7 1796.3 1939.9 1996.4 1997.3 2012.5 1909.3 1919.4 1938.1 1938.5 1941.3 1945.8 1986.1 2012.5 2043.4 2052.1 7237.8 7298.6 7320.9 7321.4 7322.5 4217.1 4359.2 4361.4 4363.2 4365.1 2590.2 2693.5 2799.5 2803.5 2808.6 946.8 1018.8 1020.8 1023.6 1070.9 9545.1 9570.7 9571.6 9574.7 9575.6 3557.9 3839.9 3852.5 3882.4 3886.7 8396.6 8399.8 8405.3 8412.5 8417.3 4151.7 4202.0 4211.5 4218.9 4265.7 3773.4 3835.3 3836.9 3838.3 3840.8 3428.4 3428.4 3440.1 3503.1 3504.1 1098.5 1122.2 1129.4 1133.2 1142.9 562.2 597.9 626.3 635.9 643.5 4470.6 4522.9 4583.4 4641.3 4759.4 802.0 808.1 810.0 812.5 818.9 3114.1 3128.8 3153.9 3159.7 3265.5 8160.9 8673.8 8674.2 8682.0 8683.6 8568.1 8624.3 8624.4 8632.7 8651.9 6552.1 6715.8 6717.4 6723.9 6728.7 4394.6 4575.7 4576.8 4587.2 4628.9 7690.6 7851.5 7853.6 7906.2 7955.1 7930.6 7990.8 8055.9 8061.8 8104.2 5955.2 6005.4 6034.1 6044.3 6074.4 6669.0 6760.9 6761.0 6762.5 6762.8 6125.9 6269.5 6296.5 6316.8 6437.9 6189.3 6233.8 6293.3 6297.3 6297.7 5370.8 5492.0 5496.3 5499.5 5501.9 4007.1 4033.5 4038.8 4084.6 4157.4 3998.8 4001.8 4008.8 4046.6 4081.8 19:41:20 INFO - PROCESS | 4861 | 19:41:20 INFO - PROCESS | 4861 | [#4] prototype.html Cycles:180 Average:4161.81 Median:4652.92 stddev:1148.14 (24.7%) stddev-sans-first:1115.21 19:41:20 INFO - PROCESS | 4861 | Values: 353.7 353.7 356.8 363.4 369.5 4672.9 4783.8 4838.0 4878.3 4914.0 4636.4 4756.3 4787.7 4793.2 4810.6 3828.6 3837.6 3848.3 3849.7 3852.2 3640.7 3714.3 3880.7 3883.3 3892.8 3871.6 3878.4 3885.6 3886.2 3899.8 3876.8 3880.2 3884.2 3886.6 3886.8 3495.7 3497.8 3506.4 3507.1 3514.3 4565.9 4645.8 4669.9 4691.9 4712.6 4419.5 4421.9 4425.9 4430.4 4452.7 5001.9 5021.5 5047.7 5048.5 5050.7 3705.8 3757.3 3772.2 3817.1 3832.4 5173.7 5218.5 5228.6 5232.8 5241.9 3906.5 3907.4 3915.3 3915.4 3923.6 3907.6 3987.2 3998.3 4022.5 4025.2 4393.4 4393.9 4410.0 4417.9 4443.1 5032.4 5046.7 5047.9 5063.5 5106.8 1274.7 1298.9 1307.2 1352.7 1359.3 934.6 955.3 968.5 991.7 992.3 4004.9 4270.0 4279.4 4295.5 4307.1 4925.9 4938.2 4951.6 4955.4 4969.7 4205.3 4230.6 4259.4 4261.6 4266.7 2502.5 2612.7 2639.7 2648.4 2658.4 4624.5 4636.9 4650.5 4668.5 4670.4 5059.0 5072.5 5082.4 5092.7 5101.9 4483.0 4611.1 4648.7 4655.3 4664.3 4930.4 4950.5 4957.6 4958.5 4985.6 4873.6 4912.6 4937.7 4951.4 4967.9 4429.4 4671.4 4672.3 4690.4 4729.3 3738.4 3740.8 3756.9 3768.3 3774.2 4907.9 4910.3 4911.4 4978.3 5015.5 4812.4 4827.8 4829.2 4862.4 4881.4 4442.7 4865.6 4913.7 5043.4 5043.8 4967.0 4994.1 4997.6 4997.8 4999.1 4876.2 4976.6 4991.3 5003.8 5042.3 4875.2 4905.7 4912.5 4947.3 4952.2 19:41:20 INFO - PROCESS | 4861 | 19:41:20 INFO - PROCESS | 4861 | [#5] yui.html Cycles:180 Average:14726.18 Median:4212.06 stddev:17738.23 (421.1%) stddev-sans-first:17756.55 19:41:20 INFO - PROCESS | 4861 | Values: 661.2 669.9 730.9 751.8 773.3 12362.3 12905.2 12912.7 12946.7 12991.0 12979.4 13077.3 13104.1 13113.2 13217.7 658.4 686.1 797.5 814.9 824.6 831.9 833.7 834.5 834.8 835.1 876.5 883.2 883.3 883.4 884.6 700.3 721.1 833.8 859.9 864.5 637.2 637.6 638.7 638.8 639.2 12530.3 12894.5 12938.5 12963.5 12977.3 12710.2 12839.2 12927.7 12955.6 12958.4 11657.9 11715.3 11806.8 11998.0 12003.3 4112.6 4136.9 4137.4 4172.5 4173.7 59012.0 60043.0 60757.1 61510.1 62312.4 10025.9 10106.9 10222.7 10273.7 10452.1 19011.7 19019.8 19034.5 19034.9 19048.1 6469.3 6742.2 6806.3 6812.3 6817.5 3631.0 3640.3 3661.0 3767.9 3782.6 2679.9 2744.4 2749.4 2771.4 2772.8 465.0 467.9 471.0 476.0 480.4 467.6 468.8 472.5 552.2 590.3 7463.8 7991.6 8144.3 8144.4 8575.1 462.1 466.8 481.7 538.4 577.3 3920.7 4129.6 4172.5 4182.2 4241.9 47619.3 62082.4 63493.4 64653.0 65376.3 46136.8 53397.5 55442.6 55796.2 56049.0 44489.3 53197.0 55972.4 56368.4 57531.0 8735.3 9339.1 9488.3 9511.5 9524.7 35831.8 35859.8 37138.4 37572.8 37925.2 43605.0 43648.0 45032.3 46793.8 47342.3 11703.0 11844.0 11935.7 12170.1 12253.0 11975.6 12344.3 12724.6 12892.6 13222.5 11777.0 12047.7 12168.4 12222.1 12735.4 11578.4 11738.7 11884.2 12245.5 12310.9 12102.8 12454.5 12516.9 12975.5 13121.5 10478.7 10539.6 10788.5 10990.1 11271.8 9327.9 9751.3 9797.7 10013.0 10398.1 19:41:20 INFO - PROCESS | 4861 | -------- Summary: end -------- 19:41:20 INFO - PROCESS | 4861 | 19:41:21 INFO - TEST-INFO | 4861: exit 0 19:41:21 INFO - TEST-OK | dromaeo_css | took 1121495ms 19:41:21 INFO - TEST-START | kraken 19:41:21 INFO - operating with platform_type : linux_ 19:41:21 INFO - Initialising browser for kraken test... 19:41:21 INFO - TEST-INFO | started process 5161 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpN7CUsU/profile http://localhost:36025/getInfo.html) 19:41:21 INFO - PROCESS | 5161 | 1483069281789 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 19:41:25 INFO - PROCESS | 5161 | __metrics Screen width/height:1600/1200 19:41:25 INFO - PROCESS | 5161 | colorDepth:24 19:41:25 INFO - PROCESS | 5161 | Browser inner width/height: 1024/697 19:41:25 INFO - PROCESS | 5161 | __metrics 19:41:25 INFO - PROCESS | 5161 | 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" 19:41:25 INFO - PROCESS | 5161 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:41:26 INFO - PROCESS | 5161 | console.error: PushService: 19:41:26 INFO - PROCESS | 5161 | stateChangeProcessEnqueue: Error transitioning state 19:41:26 INFO - PROCESS | 5161 | UnknownError 19:41:26 INFO - TEST-INFO | 5161: exit 0 19:41:26 INFO - Browser initialized. 19:41:26 INFO - Running cycle 1/1 for kraken test... 19:41:26 INFO - TEST-INFO | started process 5244 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpN7CUsU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:41:38 INFO - PROCESS | 5244 | RSS: Main: 151834624 19:41:38 INFO - PROCESS | 5244 | 19:41:41 INFO - PROCESS | 5244 | Cycle 1(1): loaded http://localhost:36025/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:36025/tests/kraken/driver.html?testName=audio-beat-detection) 19:41:42 INFO - PROCESS | 5244 | RSS: Main: 205258752 19:41:42 INFO - PROCESS | 5244 | 19:41:45 INFO - PROCESS | 5244 | Cycle 1(1): loaded http://localhost:36025/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:36025/tests/kraken/driver.html?testName=audio-dft) 19:41:45 INFO - PROCESS | 5244 | RSS: Main: 184324096 19:41:45 INFO - PROCESS | 5244 | 19:41:49 INFO - PROCESS | 5244 | Cycle 1(1): loaded http://localhost:36025/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:36025/tests/kraken/driver.html?testName=audio-fft) 19:41:49 INFO - PROCESS | 5244 | RSS: Main: 266911744 19:41:49 INFO - PROCESS | 5244 | 19:41:51 INFO - PROCESS | 5244 | Cycle 1(1): loaded http://localhost:36025/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:36025/tests/kraken/driver.html?testName=audio-oscillator) 19:41:52 INFO - PROCESS | 5244 | RSS: Main: 179027968 19:41:52 INFO - PROCESS | 5244 | 19:41:54 INFO - PROCESS | 5244 | Cycle 1(1): loaded http://localhost:36025/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:36025/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:41:55 INFO - PROCESS | 5244 | RSS: Main: 181956608 19:41:55 INFO - PROCESS | 5244 | 19:41:58 INFO - PROCESS | 5244 | Cycle 1(1): loaded http://localhost:36025/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:36025/tests/kraken/driver.html?testName=imaging-darkroom) 19:41:59 INFO - PROCESS | 5244 | RSS: Main: 222031872 19:41:59 INFO - PROCESS | 5244 | 19:42:02 INFO - PROCESS | 5244 | Cycle 1(1): loaded http://localhost:36025/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:36025/tests/kraken/driver.html?testName=imaging-desaturate) 19:42:03 INFO - PROCESS | 5244 | RSS: Main: 215117824 19:42:03 INFO - PROCESS | 5244 | 19:42:06 INFO - PROCESS | 5244 | Cycle 1(1): loaded http://localhost:36025/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:36025/tests/kraken/driver.html?testName=json-parse-financial) 19:42:06 INFO - PROCESS | 5244 | RSS: Main: 216391680 19:42:06 INFO - PROCESS | 5244 | 19:42:08 INFO - PROCESS | 5244 | Cycle 1(1): loaded http://localhost:36025/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:36025/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:42:09 INFO - PROCESS | 5244 | RSS: Main: 180740096 19:42:09 INFO - PROCESS | 5244 | 19:42:10 INFO - PROCESS | 5244 | Cycle 1(1): loaded http://localhost:36025/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:36025/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:42:11 INFO - PROCESS | 5244 | RSS: Main: 188678144 19:42:11 INFO - PROCESS | 5244 | 19:42:13 INFO - PROCESS | 5244 | Cycle 1(1): loaded http://localhost:36025/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:36025/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:42:14 INFO - PROCESS | 5244 | RSS: Main: 197206016 19:42:14 INFO - PROCESS | 5244 | 19:42:17 INFO - PROCESS | 5244 | Cycle 1(1): loaded http://localhost:36025/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:36025/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:42:17 INFO - PROCESS | 5244 | RSS: Main: 206303232 19:42:17 INFO - PROCESS | 5244 | 19:42:20 INFO - PROCESS | 5244 | Cycle 1(1): loaded http://localhost:36025/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:36025/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:42:21 INFO - PROCESS | 5244 | RSS: Main: 201625600 19:42:21 INFO - PROCESS | 5244 | 19:42:23 INFO - PROCESS | 5244 | Cycle 1(1): loaded http://localhost:36025/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:36025/tests/kraken/driver.html?testName=ai-astar) 19:42:23 INFO - PROCESS | 5244 | RSS: Main: 250626048 19:42:23 INFO - PROCESS | 5244 | 19:42:23 INFO - PROCESS | 5244 | __start_tp_report 19:42:23 INFO - PROCESS | 5244 | _x_x_mozilla_page_load 19:42:23 INFO - PROCESS | 5244 | _x_x_mozilla_page_load_details 19:42:23 INFO - PROCESS | 5244 | |i|pagename|runs| 19:42:23 INFO - PROCESS | 5244 | |0;ai-astar;106;116;114;115;114;115;114;114;115;113 19:42:23 INFO - PROCESS | 5244 | |1;audio-beat-detection;156;126;127;165;127;126;126;131;149;127 19:42:23 INFO - PROCESS | 5244 | |2;audio-dft;161;161;162;160;163;162;161;163;162;161 19:42:23 INFO - PROCESS | 5244 | |3;audio-fft;77;75;77;113;77;77;78;78;95;78 19:42:23 INFO - PROCESS | 5244 | |4;audio-oscillator;86;86;107;86;85;85;110;86;86;115 19:42:23 INFO - PROCESS | 5244 | |5;imaging-gaussian-blur;118;119;117;117;117;118;117;117;117;117 19:42:23 INFO - PROCESS | 5244 | |6;imaging-darkroom;149;152;149;150;148;150;149;149;149;150 19:42:23 INFO - PROCESS | 5244 | |7;imaging-desaturate;96;95;96;96;95;95;96;96;95;98 19:42:23 INFO - PROCESS | 5244 | |8;json-parse-financial;69;67;72;66;67;68;67;67;69;67 19:42:23 INFO - PROCESS | 5244 | |9;json-stringify-tinderbox;71;68;68;68;67;67;67;67;68;75 19:42:23 INFO - PROCESS | 5244 | |10;stanford-crypto-aes;86;86;84;85;85;83;93;84;84;93 19:42:23 INFO - PROCESS | 5244 | |11;stanford-crypto-ccm;135;134;135;142;144;133;134;131;179;131 19:42:23 INFO - PROCESS | 5244 | |12;stanford-crypto-pbkdf2;175;172;174;173;175;173;175;178;175;171 19:42:23 INFO - PROCESS | 5244 | |13;stanford-crypto-sha256-iterative;68;68;68;68;68;67;67;72;68;67 19:42:23 INFO - PROCESS | 5244 | __end_tp_report 19:42:23 INFO - PROCESS | 5244 | __start_cc_report 19:42:23 INFO - PROCESS | 5244 | _x_x_mozilla_cycle_collect,1786 19:42:23 INFO - PROCESS | 5244 | __end_cc_report 19:42:23 INFO - PROCESS | 5244 | __startTimestamp1483069343577__endTimestamp 19:42:23 INFO - PROCESS | 5244 | 19:42:23 INFO - PROCESS | 5244 | ------- Summary: start ------- 19:42:23 INFO - PROCESS | 5244 | Number of tests: 14 19:42:23 INFO - PROCESS | 5244 | 19:42:23 INFO - PROCESS | 5244 | [#0] ai-astar Cycles:10 Average:113.60 Median:114.50 stddev:2.80 (2.4%) stddev-sans-first:0.88 19:42:23 INFO - PROCESS | 5244 | Values: 106.0 116.0 114.0 115.0 114.0 115.0 114.0 114.0 115.0 113.0 19:42:23 INFO - PROCESS | 5244 | 19:42:23 INFO - PROCESS | 5244 | [#1] audio-beat-detection Cycles:10 Average:136.00 Median:129.00 stddev:14.82 (11.5%) stddev-sans-first:13.85 19:42:23 INFO - PROCESS | 5244 | Values: 156.0 126.0 127.0 165.0 127.0 126.0 126.0 131.0 149.0 127.0 19:42:23 INFO - PROCESS | 5244 | 19:42:23 INFO - PROCESS | 5244 | [#2] audio-dft Cycles:10 Average:161.60 Median:162.00 stddev:0.97 (0.6%) stddev-sans-first:1.00 19:42:23 INFO - PROCESS | 5244 | Values: 161.0 161.0 162.0 160.0 163.0 162.0 161.0 163.0 162.0 161.0 19:42:23 INFO - PROCESS | 5244 | 19:42:23 INFO - PROCESS | 5244 | [#3] audio-fft Cycles:10 Average:82.50 Median:77.50 stddev:12.13 (15.7%) stddev-sans-first:12.70 19:42:23 INFO - PROCESS | 5244 | Values: 77.0 75.0 77.0 113.0 77.0 77.0 78.0 78.0 95.0 78.0 19:42:23 INFO - PROCESS | 5244 | 19:42:23 INFO - PROCESS | 5244 | [#4] audio-oscillator Cycles:10 Average:93.20 Median:86.00 stddev:12.21 (14.2%) stddev-sans-first:12.67 19:42:23 INFO - PROCESS | 5244 | Values: 86.0 86.0 107.0 86.0 85.0 85.0 110.0 86.0 86.0 115.0 19:42:23 INFO - PROCESS | 5244 | 19:42:23 INFO - PROCESS | 5244 | [#5] imaging-gaussian-blur Cycles:10 Average:117.40 Median:117.00 stddev:0.70 (0.6%) stddev-sans-first:0.71 19:42:23 INFO - PROCESS | 5244 | Values: 118.0 119.0 117.0 117.0 117.0 118.0 117.0 117.0 117.0 117.0 19:42:23 INFO - PROCESS | 5244 | 19:42:23 INFO - PROCESS | 5244 | [#6] imaging-darkroom Cycles:10 Average:149.50 Median:149.50 stddev:1.08 (0.7%) stddev-sans-first:1.13 19:42:23 INFO - PROCESS | 5244 | Values: 149.0 152.0 149.0 150.0 148.0 150.0 149.0 149.0 149.0 150.0 19:42:23 INFO - PROCESS | 5244 | 19:42:23 INFO - PROCESS | 5244 | [#7] imaging-desaturate Cycles:10 Average:95.80 Median:96.00 stddev:0.92 (1.0%) stddev-sans-first:0.97 19:42:23 INFO - PROCESS | 5244 | Values: 96.0 95.0 96.0 96.0 95.0 95.0 96.0 96.0 95.0 98.0 19:42:23 INFO - PROCESS | 5244 | 19:42:23 INFO - PROCESS | 5244 | [#8] json-parse-financial Cycles:10 Average:67.90 Median:67.50 stddev:1.73 (2.6%) stddev-sans-first:1.79 19:42:23 INFO - PROCESS | 5244 | Values: 69.0 67.0 72.0 66.0 67.0 68.0 67.0 67.0 69.0 67.0 19:42:23 INFO - PROCESS | 5244 | 19:42:23 INFO - PROCESS | 5244 | [#9] json-stringify-tinderbox Cycles:10 Average:68.60 Median:68.00 stddev:2.55 (3.7%) stddev-sans-first:2.55 19:42:23 INFO - PROCESS | 5244 | Values: 71.0 68.0 68.0 68.0 67.0 67.0 67.0 67.0 68.0 75.0 19:42:23 INFO - PROCESS | 5244 | 19:42:23 INFO - PROCESS | 5244 | [#10] stanford-crypto-aes Cycles:10 Average:86.30 Median:85.50 stddev:3.65 (4.3%) stddev-sans-first:3.87 19:42:23 INFO - PROCESS | 5244 | Values: 86.0 86.0 84.0 85.0 85.0 83.0 93.0 84.0 84.0 93.0 19:42:23 INFO - PROCESS | 5244 | 19:42:23 INFO - PROCESS | 5244 | [#11] stanford-crypto-ccm Cycles:10 Average:139.80 Median:135.00 stddev:14.43 (10.7%) stddev-sans-first:15.20 19:42:23 INFO - PROCESS | 5244 | Values: 135.0 134.0 135.0 142.0 144.0 133.0 134.0 131.0 179.0 131.0 19:42:23 INFO - PROCESS | 5244 | 19:42:23 INFO - PROCESS | 5244 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.10 Median:175.00 stddev:1.97 (1.1%) stddev-sans-first:2.06 19:42:23 INFO - PROCESS | 5244 | Values: 175.0 172.0 174.0 173.0 175.0 173.0 175.0 178.0 175.0 171.0 19:42:23 INFO - PROCESS | 5244 | 19:42:23 INFO - PROCESS | 5244 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.10 Median:68.00 stddev:1.45 (2.1%) stddev-sans-first:1.54 19:42:23 INFO - PROCESS | 5244 | Values: 68.0 68.0 68.0 68.0 68.0 67.0 67.0 72.0 68.0 67.0 19:42:23 INFO - PROCESS | 5244 | -------- Summary: end -------- 19:42:23 INFO - PROCESS | 5244 | 19:42:24 INFO - TEST-INFO | 5244: exit 0 19:42:24 INFO - TEST-OK | kraken | took 63513ms 19:42:24 INFO - SUITE-END | took 1185s 19:42:25 INFO - Completed test suite (00:19:45) 19:42:25 INFO - javascript benchmark 19:42:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2553.323369307134, 2556.169244995377, 2581.1998280533035, 2600.492903883742, 2609.2041927212194, 4581.587657110859, 4600.953990460102, 4740.525947405258, 4745.4305483342005, 4747.145513807518, 4675.088357770236, 4782.952170478294, 4808.975955120228, 4857.538533839289, 4888.07126645937, 4416.97791511042, 4461.196984542781, 4484.798184081724, 4495.258282383419, 4537.954620453803, 4511.977440112795, 4518.593326600605, 4530.411046563942, 4539.092181563684, 4558.202314594949, 4341.3053911374345, 4360.324149756792, 4418.425604671388, 4425.645948324126, 4441.844535441261, 4377.277749171383, 4380.18966491197, 4399.098184872126, 4415.359772832953, 4441.511433742286, 4340.414044104069, 4382.298832186867, 4396.516383197845, 4432.734035957853, 4457.821687132511, 4755.334253204554, 4796.256580230035, 4805.879853003668, 4816.975915120437, 4839.951600483985, 5031.396232452093, 5065.240213967897, 5113.616478764107, 5121.103806833827, 5142.948570514322, 5207.9479205207845, 5212.400573934008, 5216.9739151304, 5238.05714971305, 5257.053730328539, 4836.177849765549, 4853.927191092136, 4855.198892182821, 4861.635377346643, 4905.484924082992, 5071.974640126776, 5111.616628752859, 5123.948760512421, 5300.0, 5335.226392173113, 4944.728039957837, 5020.0, 5020.447750747415, 5042.722650254272, 5083.542481176712, 5056.797728090835, 5096.108181068298, 5126.743662816844, 5134.306868572769, 5138.614603904647, 5028.899422011539, 5105.719185444762, 5114.130597798383, 5125.15434953236, 5148.794048238104, 5161.045206636784, 5167.069927413027, 5168.819091331786, 5171.146760784503, 5182.689038657617, 4253.766042867672, 4280.336547835091, 4323.113761678848, 4342.717723347972, 4457.175422546774, 3781.4327850822597, 3787.337215987191, 3795.848166073326, 3801.258754542893, 3824.8470061197795, 4379.64962802975, 4383.386325914311, 4383.9342409863875, 4392.978035109868, 4421.46942366918, 5048.899022019613, 5070.645054846125, 5075.416327122354, 5149.0474262261605, 5202.609804264695, 4346.087321662423, 4347.108842687241, 4367.934480982788, 4396.934045989313, 4406.206882761134, 4622.930656040162, 4631.235846085426, 4641.164590373698, 4670.136024835417, 4670.462896766848, 4717.622590192845, 4746.145693775154, 4787.736674482798, 4790.616750659939, 4794.928076078932, 5105.4639262878345, 5118.155504341742, 5135.409427915837, 5135.589152867837, 5148.227765835155, 4785.042991401664, 4808.591269742111, 4827.879303017453, 4828.444728856227, 4830.7101573905675, 5061.139606267017, 5074.416442109205, 5075.086484432837, 5075.416327122428, 5077.619178561548, 5150.381954165374, 5159.045576568347, 5159.793608255778, 5164.741762911764, 5175.37895452533, 4975.253711943237, 5002.174641184164, 5010.298558201782, 5023.572996295356, 5024.397072351195, 4956.851294461172, 5018.949810501995, 5025.070361983045, 5025.547700706954, 5034.119029169954, 4889.095517329305, 4916.655834091579, 4938.456769755396, 4960.851174464772, 4991.550760431579, 5006.824761133343, 5010.649254552146, 5011.373578302712, 5015.448300686849, 5057.190849464068, 4818.783154757974, 4825.30033145199, 4871.707697538159, 4892.2661600760175, 4896.6572339935865], "value": 4702.29907619213, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [520.0716720653634, 536.8979893820174, 545.9044667183242, 548.5584104795638, 559.2254727202826, 36862.0, 38856.22287554247, 39026.60973390265, 42633.0, 42651.0, 39047.21905561894, 39461.278114673354, 39513.0, 39534.40698389519, 39568.60431395685, 7375.815604609901, 7524.247575242473, 7543.886841697379, 7695.268949449794, 7697.191794861543, 9795.804083918318, 10104.242181836355, 10110.44392558409, 10395.84406233907, 10404.0, 9933.106020458157, 10001.89998100017, 10011.699649010505, 10013.349132306414, 10014.399136051823, 9709.951450242774, 9730.513474326292, 9735.17251033663, 9739.513024348755, 9746.853797193047, 9914.9504252479, 9954.253430992632, 9973.85039224405, 9976.301658883882, 9977.850332245021, 36488.0, 36790.26419471623, 36829.07927301812, 36835.815820920994, 36918.815405922796, 3558.6085530591613, 3597.334493118781, 3615.1142969972525, 3615.204654975901, 3654.214343916071, 27062.023177450465, 27409.725902740916, 27849.58225626617, 28113.718862811314, 28114.297142571395, 3363.983180084084, 3389.8983030509125, 3493.7205023598053, 3552.8401221945073, 3563.376409128392, 232589.0, 234623.0, 235825.0, 236084.0, 236375.81812091, 49588.752056239486, 49862.0, 49931.25103123456, 49944.50055499398, 49970.50029499731, 50987.7450612752, 51008.74495627498, 51087.0, 51105.233421498706, 51124.74437627863, 13537.59387218385, 13895.861041389659, 13907.79138312926, 13916.930415347859, 13918.234497102756, 9798.363106398061, 10042.347247428894, 10051.095401413908, 10298.588056477656, 10836.29564078348, 13774.380152893144, 14401.135931844123, 14650.413983440756, 14820.3330850112, 15173.0, 5964.850878728066, 5979.19280897082, 6040.275166979991, 6114.691458015536, 6180.4437600615265, 736.2306389822648, 739.4638886807129, 747.8466914282668, 755.3692666623322, 763.8510490454308, 25063.498730025298, 25835.741642583333, 25904.611430828554, 26383.0, 26435.206943791716, 904.7783293093235, 913.1325241021057, 925.3476299209042, 931.4550987672287, 1057.9894201058046, 13892.930535347461, 13966.092204006707, 14173.71652566943, 14326.641833954234, 14457.92771036138, 330693.0, 331836.0, 332798.7201279853, 333119.0, 333388.0, 46470.30294545517, 46504.7674761624, 46563.76718116388, 46672.5332746675, 46704.532954670016, 1127.5715228213226, 1141.3437273567731, 1157.5080590748898, 1159.7796418680423, 1162.4594563528, 25067.0, 25735.0, 26011.219663410127, 26369.0, 26447.206583802516, 39159.80420097881, 39626.20747584975, 39689.20621587495, 39708.404373934995, 39751.204975900895, 269526.0, 269907.0, 270831.0, 271108.0, 271170.0, 33216.83391583027, 33767.83116084404, 33895.83052084724, 34151.31697366088, 34165.0, 37928.62071379251, 38382.80808595939, 38965.41551876668, 39375.2124957505, 39428.80285598554, 32423.189420264684, 32739.836300818344, 32951.0, 33206.16984575308, 33458.66541334555, 32602.673973260913, 32755.0, 33069.66930330762, 33486.497702534965, 34118.31763364669, 32570.51144232791, 32775.836120819244, 32778.344433111684, 32982.0, 33033.83483082569, 25636.743632564183, 27421.58867617027, 29243.41513169767, 29449.263768405963, 32358.83820580882, 22672.0, 22740.09039638462, 22778.886105569367, 22904.0, 22950.885245573667], "value": 17097.59409245381, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.98420571074297, 354.14827340246717, 372.2424865398913, 372.4450568652708, 388.6269181585678, 13105.410256538455, 13379.531716389929, 13969.371378287977, 16192.190390480488, 16864.83135168648, 16126.435574754885, 16159.434419795309, 16453.67092658146, 16572.08853513059, 16574.83976121672, 4730.337752714614, 5105.566026887719, 5128.564072053861, 5136.280920671109, 5136.922946155811, 3866.3620502617173, 4062.6343629073376, 5033.395992480889, 5115.386153661547, 5123.257127716479, 5620.381758006616, 5900.321463031742, 5913.970430147843, 5980.222571065756, 5981.88036239275, 5760.596758226926, 5904.822855314347, 5938.970305148489, 5950.077737950624, 5967.04527275636, 5215.869603259911, 5229.424763276036, 5230.8692282692855, 5231.555317797992, 5232.686038837682, 18911.90544047285, 19453.9027304864, 19522.609547809105, 19525.0, 19528.414147575597, 2479.578471659804, 2521.0924067335745, 2530.72162062173, 2563.090103013422, 2599.714031456538, 12087.576934807328, 12118.757624847542, 12310.384480776014, 12708.0, 12765.36173191346, 1671.9498415047567, 1734.2976094681674, 1749.9037552934583, 1767.805541390446, 1772.0519522055638, 59239.40760592382, 59490.40509594892, 59510.40489595049, 59548.702256488876, 59928.40071599272, 9063.365564410427, 9289.535523223813, 9356.391834530734, 9471.479068651153, 9498.762530936781, 12956.546520871725, 12960.675983100498, 12974.156679815782, 12982.0, 12998.545050923172, 6724.058631791553, 6842.247352791189, 6971.442284617218, 7019.543729657556, 7054.788356349318, 6436.613803171825, 6529.477641788645, 6647.900281495781, 6664.86670266592, 6673.733050677918, 5834.795782147605, 5850.5189412299615, 5851.0, 5961.493273071837, 6064.272287325549, 1691.3910992042854, 1762.118940529735, 1765.9470215893543, 1791.5073354827528, 1823.7902641196167, 751.8007727952097, 768.9692412303446, 770.4667711254563, 784.4704824243613, 788.3456730913329, 14812.925935370255, 15059.02116362433, 15666.764998525032, 15787.842121578868, 16468.917655411646, 783.5024759277786, 785.9331956783737, 788.5347644889542, 806.8426656801868, 811.0146172400562, 8961.372703910794, 9011.549422528848, 9199.264058875275, 9418.29362797793, 9433.633343460997, 320625.50930115004, 321151.0, 323229.0, 323604.0, 325915.0, 60783.39216607777, 62027.379726203064, 62435.37564624386, 62578.687106565085, 63559.364406355344, 904.0552622509625, 912.3020889019905, 938.9295802814817, 939.9895112753817, 944.2021491839384, 13723.519676811264, 13838.23889686077, 13912.078463522774, 13920.234387108807, 14481.275936202937, 31768.0, 32081.67918320787, 32153.678463215067, 32675.0, 33720.32559348848, 39667.404988925766, 40670.0, 41112.79443602763, 41387.0, 42912.78543607387, 6351.491880649629, 6446.194225721785, 6668.766593169215, 7198.49610527258, 7617.809554761176, 7068.611226382598, 7369.742059027908, 11001.094611412833, 11885.940570297093, 12272.140950133406, 9408.81182376335, 9626.133647971716, 9691.079347462262, 9775.21798256152, 9904.0, 10149.34029288094, 10311.329763565343, 10402.73993150147, 10584.682459526226, 10756.354618722902, 9544.618215271312, 9545.37728586156, 9727.805443891224, 10016.499175041365, 10136.239782016231, 6810.250872403933, 6919.481038922, 7037.1555413350725, 7051.964740176266, 7141.928580714334, 9368.0, 9689.079537443931, 9796.216302695624, 9937.055979681918, 10069.194464442973], "value": 7887.592399847145, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1289.6904742861716, 1299.9935000324997, 1302.5180683147237, 1305.4060402516852, 1308.2150709574257, 6592.736290548379, 6943.548669336502, 6945.09713737214, 6948.339907708772, 6954.791356259314, 6201.565890387675, 6420.325865784095, 6935.237123916378, 7161.355478006978, 7164.068671072753, 1991.1338567723014, 2028.3712049264702, 2029.6752519596866, 2030.9187632494682, 2032.9898350508226, 1763.841254287114, 2031.0, 2059.794020597943, 2070.33749200256, 2074.6680531115026, 1796.2814874050418, 1939.864209505335, 1996.4210378990076, 1997.3308941504613, 2012.476756043432, 1909.2840184930512, 1919.443361425192, 1938.0697265312658, 1938.5250613599617, 1941.2817257614772, 1945.805419458057, 1986.0764744393782, 2012.5169959209832, 2043.3767700851236, 2052.127845665601, 7237.819054523627, 7298.598577078258, 7320.890186647204, 7321.37768289696, 7322.5240359377, 4217.135487225142, 4359.215341238576, 4361.389405483234, 4363.170997510463, 4365.061511774984, 2590.248827839924, 2693.5151672698908, 2799.482095812261, 2803.4533266013136, 2808.578713193017, 946.8081391578473, 1018.7758693087509, 1020.8264595018791, 1023.5598692562195, 1070.9089727373182, 9545.093216144454, 9570.665026724031, 9571.617135314578, 9574.712758617183, 9575.61697532098, 3557.911052223715, 3839.9040023999623, 3852.4799152114665, 3882.3594106972596, 3886.7473614214987, 8396.580170991549, 8399.790005249917, 8405.327573794082, 8412.453190542717, 8417.284530814826, 4151.6886233532605, 4202.012527056139, 4211.494620645542, 4218.873433796991, 4265.680073994463, 3773.358529050013, 3835.328817456934, 3836.8848934532007, 3838.3091043612417, 3840.8271627776817, 3428.400029994741, 3428.434308339146, 3440.1055725511546, 3503.1417302760988, 3504.141485336058, 1098.5001824170142, 1122.180808010157, 1129.3675541696675, 1133.1897693149438, 1142.9428528573537, 562.2406330412991, 597.8953683105526, 626.264333252153, 635.9236891573041, 643.5141468191486, 4470.552944705503, 4522.932156017662, 4583.404157459576, 4641.257398816173, 4759.428868535798, 801.9799505012421, 808.1474044882705, 809.9514029158152, 812.5165526511717, 818.8853560501534, 3114.143610507129, 3128.765342599314, 3153.8738450462183, 3159.6524382317925, 3265.5264986576813, 8160.918390816016, 8673.783155421164, 8674.175953284428, 8681.956590217134, 8683.56582170889, 8568.143185681382, 8624.310055195569, 8624.439411438236, 8632.697855575025, 8651.870221946676, 6552.148220731275, 6715.7985260442265, 6717.361850624183, 6723.865522689519, 6728.697208625624, 4394.604485596312, 4575.7483338416705, 4576.839810606612, 4587.220172570737, 4628.907421851612, 7690.577018264049, 7851.489653172525, 7853.607319634109, 7906.209379062047, 7955.1249362571225, 7930.56381898978, 7990.800229994296, 8055.879161812694, 8061.798455038671, 8104.230098140667, 5955.225820643289, 6005.429484198994, 6034.125051867542, 6044.274687037584, 6074.36219196978, 6669.0, 6760.932390676227, 6760.966195168992, 6762.459003279824, 6762.763303284361, 6125.877482450415, 6269.529785266032, 6296.464800492009, 6316.84207894788, 6437.871242575028, 6189.319174890848, 6233.75064997395, 6293.339199383999, 6297.275813281532, 6297.685115744286, 5370.758315875717, 5492.038893193595, 5496.285482887199, 5499.450054994469, 5501.889962200814, 4007.1184339445276, 4033.5361433435532, 4038.8384464621085, 4084.550699423002, 4157.355609880473, 3998.760074395429, 4001.8199181037508, 4008.8396464141106, 4046.575109613566, 4081.7959102044183], "value": 3749.627476833516, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.6622525488159, 353.7346989757682, 356.8340721564473, 363.42034455044194, 369.47718977646633, 4672.859814205571, 4783.760811959405, 4838.0, 4878.341423907771, 4913.975430122849, 4636.443626764784, 4756.2865570164495, 4787.677976181549, 4793.233082706767, 4810.618743721854, 3828.636279553438, 3837.57786643469, 3848.326542855003, 3849.7305188636806, 3852.1717830666403, 3640.690541303979, 3714.331420344337, 3880.7477513961644, 3883.26218018577, 3892.785896775676, 3871.5741268460447, 3878.3600705883496, 3885.5920128386397, 3886.1839013807135, 3899.844006239747, 3876.7867767272787, 3880.185161116169, 3884.184321292532, 3886.6113388660906, 3886.7862267575415, 3495.6504349565093, 3497.8251087445524, 3506.3513250048823, 3507.1232191952013, 3514.279572687593, 4565.885852853672, 4645.7909394077005, 4669.88325291867, 4691.859244222679, 4712.622990160813, 4419.491758447755, 4421.9115617687785, 4425.867223983253, 4430.4240448741775, 4452.732836029849, 5001.874953126165, 5021.497850214986, 5047.722375269359, 5048.495150484959, 5050.7222102784335, 3705.7961812100325, 3757.2528774120165, 3772.2270532849516, 3817.141143242776, 3832.386818109117, 5173.741312934339, 5218.478152184751, 5228.633995620308, 5232.816851410221, 5241.872997305598, 3906.453096566483, 3907.394353875097, 3915.295246855593, 3915.375119325505, 3923.6076392360537, 3907.5737355864903, 3987.1626958338493, 3998.28030954431, 4022.456968309241, 4025.19496100781, 4393.428854248928, 4393.890152746143, 4409.977950110229, 4417.867463976086, 4443.133588950123, 5032.421271553745, 5046.6719663221775, 5047.924281135786, 5063.544281014652, 5106.821261255838, 1274.6877015131167, 1298.9220646761225, 1307.1764788183573, 1352.7362164377853, 1359.2592037339673, 934.5934518484481, 955.254901177083, 968.4504792332212, 991.6628346362127, 992.3202606214766, 4004.8998775030273, 4270.0, 4279.379489973936, 4295.52749197588, 4307.135754723136, 4925.852224433273, 4938.185199442124, 4951.628627852925, 4955.405351357788, 4969.701817890938, 4205.2640787862, 4230.598093181143, 4259.403683484315, 4261.595148460891, 4266.701330906807, 2502.4995000999875, 2612.7197673140404, 2639.6832380114124, 2648.4241876083697, 2658.388570628766, 4624.491305956342, 4636.907261854812, 4650.511696271843, 4668.533146685304, 4670.416197975253, 5059.0, 5072.503611434636, 5082.415522214918, 5092.65044763138, 5101.923471147936, 4482.955170448384, 4611.054733779567, 4648.674592778473, 4655.324083329999, 4664.277037059262, 4930.422264875206, 4950.504949505021, 4957.578605818546, 4958.471179698452, 4985.551300382983, 4873.634477414279, 4912.5786791887585, 4937.679050861682, 4951.3810773653295, 4967.9006419870675, 4429.402030725811, 4671.365022242188, 4672.345871577913, 4690.390249267573, 4729.3378926950945, 3738.401855703183, 3740.775553466801, 3756.86850960226, 3768.265188288367, 3774.226283611798, 4907.926381104215, 4910.263460480956, 4911.4106307243355, 4978.303037574815, 5015.473375295542, 4812.422509298906, 4827.782749776198, 4829.227323628203, 4862.367892173995, 4881.414230292387, 4442.733820861168, 4865.6107511398295, 4913.6806107604325, 5043.36957880265, 5043.798248070036, 4966.9817687374725, 4994.077280247058, 4997.625178111583, 4997.775100120431, 4999.100161970735, 4876.195427754523, 4976.576990955809, 4991.251312303184, 5003.824866129668, 5042.319286896369, 4875.1712208923855, 4905.705657660552, 4912.484189160086, 4947.332110165082, 4952.158133117451], "value": 3818.8256106168583, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [661.2230629010911, 669.9430548403385, 730.853829234153, 751.8120469882529, 773.2692605487814, 12362.320072396025, 12905.225686458807, 12912.74174516509, 12946.741065178703, 12991.02567307451, 12979.415926283316, 13077.34613269335, 13104.148230365043, 13113.213207207551, 13217.735645287088, 658.3778329478656, 686.1491750229704, 797.5254723439573, 814.9266566009057, 824.5588610093602, 831.8960129983752, 833.679033572076, 834.5159807311775, 834.8121672623641, 835.0522157351389, 876.5354362188019, 883.244825674049, 883.3286702106413, 883.3728053082319, 884.5842454046619, 700.2787129256874, 721.07702141259, 833.791552111972, 859.8581234096366, 864.5072308783996, 637.1780403279763, 637.5855693799068, 638.6710843915388, 638.8307098618869, 639.229728177545, 12530.310832904186, 12894.548690795777, 12938.48246070156, 12963.546275880393, 12977.286249256287, 12710.237385756884, 12839.165454245538, 12927.741445171045, 12955.611331660066, 12958.352082395842, 11657.883421165849, 11715.297082175011, 11806.822897656457, 11998.0, 12003.279803211748, 4112.568180341081, 4136.875893723193, 4137.379393091031, 4172.499300083979, 4173.707840451169, 59012.0, 60043.0, 60757.088643670824, 61510.07734883935, 62312.37687623111, 10025.899741002642, 10106.949465252774, 10222.744431389276, 10273.743156421, 10452.111570516592, 19011.714824277646, 19019.809801901803, 19034.52413689669, 19034.904825475784, 19048.14283357252, 6469.320721324288, 6742.224644165885, 6806.251312355637, 6812.318768123148, 6817.5227734059135, 3631.0, 3640.2537479816574, 3661.0, 3767.9261410497875, 3782.6406491383273, 2679.852608106573, 2744.423671029066, 2749.3676454415595, 2771.390294135287, 2772.8059035867295, 464.9814007439732, 467.85730352242894, 471.04613158354607, 475.9714417134983, 480.41149591749826, 467.60720994364897, 468.75624675168723, 472.52511226217433, 552.2241251042292, 590.3034419385167, 7463.776086717407, 7991.640376183086, 8144.267015968591, 8144.429889907649, 8575.085536702327, 462.1288870479171, 466.82260740918235, 481.71097341594765, 538.3943064052941, 577.3187638586509, 3920.7059470539825, 4129.566395528426, 4172.520160181557, 4182.1844740275965, 4241.94424104762, 47619.285710714365, 62082.41258286776, 63493.36506634967, 64653.0, 65376.34623653703, 46136.76931615388, 53397.46602534003, 55442.624098228414, 55796.16305755417, 56049.0, 44489.33266001013, 53197.0, 55972.440275598354, 56368.43631563714, 57531.0, 8735.30117590604, 9339.133791629069, 9488.288378371788, 9511.528292173445, 9524.666636667684, 35831.82084089563, 35859.82070089633, 37138.44292335563, 37572.812135939144, 37925.24149517049, 43605.0, 43648.0, 45032.324515132954, 46793.76603116962, 47342.28986565136, 11703.01846166111, 11843.98652229106, 11935.701607459536, 12170.14808963364, 12253.0, 11975.580854670045, 12344.259344439362, 12724.618261452171, 12892.613221603367, 13222.537211197561, 11777.0, 12047.698807529881, 12168.391580421121, 12222.144449888778, 12735.426905789076, 11578.421078946187, 11738.70653233676, 11884.227525210834, 12245.507045966691, 12310.876891230973, 12102.75794484123, 12454.501819927102, 12516.87483125157, 12975.480980761042, 13121.475140994253, 10478.685639430829, 10539.578416863545, 10788.514516846604, 10990.120790336594, 11271.830922536004, 9327.906720932704, 9751.268654850772, 9797.706068817946, 10013.0, 10398.116160126172], "value": 6335.559567961113, "unit": "score"}], "name": "dromaeo_css", "value": 6213.324354677991, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 116.0, 114.0, 115.0, 114.0, 115.0, 114.0, 114.0, 115.0, 113.0], "value": 113.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 126.0, 127.0, 165.0, 127.0, 126.0, 126.0, 131.0, 149.0, 127.0], "value": 136.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 162.0, 160.0, 163.0, 162.0, 161.0, 163.0, 162.0, 161.0], "value": 161.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 75.0, 77.0, 113.0, 77.0, 77.0, 78.0, 78.0, 95.0, 78.0], "value": 82.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 107.0, 86.0, 85.0, 85.0, 110.0, 86.0, 86.0, 115.0], "value": 93.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 119.0, 117.0, 117.0, 117.0, 118.0, 117.0, 117.0, 117.0, 117.0], "value": 117.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 152.0, 149.0, 150.0, 148.0, 150.0, 149.0, 149.0, 149.0, 150.0], "value": 149.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 96.0, 96.0, 95.0, 95.0, 96.0, 96.0, 95.0, 98.0], "value": 95.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 72.0, 66.0, 67.0, 68.0, 67.0, 67.0, 69.0, 67.0], "value": 67.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 68.0, 68.0, 68.0, 67.0, 67.0, 67.0, 67.0, 68.0, 75.0], "value": 68.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 84.0, 85.0, 85.0, 83.0, 93.0, 84.0, 84.0, 93.0], "value": 86.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 134.0, 135.0, 142.0, 144.0, 133.0, 134.0, 131.0, 179.0, 131.0], "value": 139.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 172.0, 174.0, 173.0, 175.0, 173.0, 175.0, 178.0, 175.0, 171.0], "value": 174.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 68.0, 68.0, 68.0, 67.0, 67.0, 72.0, 68.0, 67.0], "value": 68.1, "unit": "score"}], "name": "kraken", "value": 1554.3999999999996, "alertThreshold": 2.0}]} 19:42:25 INFO - Return code: 0 19:42:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:42:25 INFO - # TBPL SUCCESS # 19:42:25 INFO - Running post-action listener: _resource_record_post_action 19:42:25 INFO - [mozharness: 2016-12-30 03:42:25.360970Z] Finished run-tests step (success) 19:42:25 INFO - Running post-run listener: _resource_record_post_run 19:42:25 INFO - Total resource usage - Wall time: 1202s; CPU: 13.0%; Read bytes: 4096; Write bytes: 387551232; Read time: 16; Write time: 221608 19:42:25 INFO - TinderboxPrint: CPU usage
13.1% 19:42:25 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 19:42:25 INFO - TinderboxPrint: I/O write bytes / time
387,551,232 / 221,608 19:42:25 INFO - TinderboxPrint: CPU idle
8,333.6 (86.8%) 19:42:25 INFO - TinderboxPrint: CPU user
1,216.4 (12.7%) 19:42:25 INFO - TinderboxPrint: Swap in / out
0 / 0 19:42:25 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 105779200; Read time: 0; Write time: 77480 19:42:25 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 0; Write bytes: 281337856; Read time: 0; Write time: 144056 19:42:25 INFO - Running post-run listener: _upload_blobber_files 19:42:25 INFO - Blob upload gear active. 19:42:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:42:26 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 19:42:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:42:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:42:26 INFO - (blobuploader) - INFO - Open directory for files ... 19:42:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:42:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:42:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:42:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:42:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:42:27 INFO - (blobuploader) - INFO - Done attempting. 19:42:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:42:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:42:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:42:30 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:42:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:42:30 INFO - (blobuploader) - INFO - Done attempting. 19:42:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:42:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:42:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:42:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:42:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:42:31 INFO - (blobuploader) - INFO - Done attempting. 19:42:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:42:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:42:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:42:32 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:42:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:42:32 INFO - (blobuploader) - INFO - Done attempting. 19:42:32 INFO - (blobuploader) - INFO - Iteration through files over. 19:42:32 INFO - Return code: 0 19:42:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:42:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:42:32 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/fc3af2f48ddf542d1858946c9ebc63d73980b5e7a6ca9c3a8b9766b541a933f98bf64d357d9961e44419f9c329ea16014ceb4fff41c4129c6b267f9ba929f092", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c3d78840784f829dfa5414b2af8c78d90bc5d497322d9162a4e1ea89028e8de1aff777d20ac25cb93eea7e71a68be512610a3ba612333c1d80382447f525d55a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c0290e7f658b4b716cd14c5629e4dce920e45ebebec79dbb2323be40d29816938a12d78d6767c44c60ab1b28e5af5bc5345a6383035ca1060b2278c9f01be672", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5099e62cad37c8d540abe1138a5b82cc11c9cf0498939a09ab5d5edbd6e4eaf8d95ceb3b5759b8a9a2743d8d2e1c01e763f41c8749effb54efc011fca7f3878c"} 19:42:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:42:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:42:32 INFO - Contents: 19:42:32 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/fc3af2f48ddf542d1858946c9ebc63d73980b5e7a6ca9c3a8b9766b541a933f98bf64d357d9961e44419f9c329ea16014ceb4fff41c4129c6b267f9ba929f092", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c3d78840784f829dfa5414b2af8c78d90bc5d497322d9162a4e1ea89028e8de1aff777d20ac25cb93eea7e71a68be512610a3ba612333c1d80382447f525d55a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c0290e7f658b4b716cd14c5629e4dce920e45ebebec79dbb2323be40d29816938a12d78d6767c44c60ab1b28e5af5bc5345a6383035ca1060b2278c9f01be672", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5099e62cad37c8d540abe1138a5b82cc11c9cf0498939a09ab5d5edbd6e4eaf8d95ceb3b5759b8a9a2743d8d2e1c01e763f41c8749effb54efc011fca7f3878c"} 19:42:32 INFO - Running post-run listener: copy_logs_to_upload_dir 19:42:32 INFO - Copying logs to upload dir... 19:42:32 INFO - mkdir: /builds/slave/test/build/upload/logs 19:42:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1248.082354 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2016-12-29 19:42:31.549869) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-29 19:42:31.552892) ========= 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=232498196343f89f466a4a9a000001c8-1483068070.617726-110951684 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/fc3af2f48ddf542d1858946c9ebc63d73980b5e7a6ca9c3a8b9766b541a933f98bf64d357d9961e44419f9c329ea16014ceb4fff41c4129c6b267f9ba929f092", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c3d78840784f829dfa5414b2af8c78d90bc5d497322d9162a4e1ea89028e8de1aff777d20ac25cb93eea7e71a68be512610a3ba612333c1d80382447f525d55a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c0290e7f658b4b716cd14c5629e4dce920e45ebebec79dbb2323be40d29816938a12d78d6767c44c60ab1b28e5af5bc5345a6383035ca1060b2278c9f01be672", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5099e62cad37c8d540abe1138a5b82cc11c9cf0498939a09ab5d5edbd6e4eaf8d95ceb3b5759b8a9a2743d8d2e1c01e763f41c8749effb54efc011fca7f3878c"} build_url:https://queue.taskcluster.net/v1/task/bYYEqKOYTxqz2HOcFDTVZQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011665 build_url: 'https://queue.taskcluster.net/v1/task/bYYEqKOYTxqz2HOcFDTVZQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/fc3af2f48ddf542d1858946c9ebc63d73980b5e7a6ca9c3a8b9766b541a933f98bf64d357d9961e44419f9c329ea16014ceb4fff41c4129c6b267f9ba929f092", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c3d78840784f829dfa5414b2af8c78d90bc5d497322d9162a4e1ea89028e8de1aff777d20ac25cb93eea7e71a68be512610a3ba612333c1d80382447f525d55a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c0290e7f658b4b716cd14c5629e4dce920e45ebebec79dbb2323be40d29816938a12d78d6767c44c60ab1b28e5af5bc5345a6383035ca1060b2278c9f01be672", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5099e62cad37c8d540abe1138a5b82cc11c9cf0498939a09ab5d5edbd6e4eaf8d95ceb3b5759b8a9a2743d8d2e1c01e763f41c8749effb54efc011fca7f3878c"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-29 19:42:31.577807) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 19:42:31.578093) ========= 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=232498196343f89f466a4a9a000001c8-1483068070.617726-110951684 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004522 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 19:42:31.663105) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-29 19:42:31.663398) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-29 19:42:31.663697) ========= ========= Total master_lag: 0.16 =========