builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-052 starttime: 1483300441.67 results: success (0) revision: 31ffcb82ced81bb75faa800d2b7e883a3761a03b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-01 11:54:01.666991) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-01 11:54:01.667505) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-01 11:54:01.667787) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-01 11:54:01.683751) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-01 11:54:01.684040) ========= 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=826874ccc97bd7bf541b4875000001d5-1483286196.284817-156018094 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-01-01 11:54:02-- 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% 45.9M=0s 2017-01-01 11:54:02 (45.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.156268 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-01 11:54:01.854057) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-01 11:54:01.854375) ========= 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=826874ccc97bd7bf541b4875000001d5-1483286196.284817-156018094 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018353 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-01 11:54:01.903911) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-01 11:54:01.904255) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 31ffcb82ced81bb75faa800d2b7e883a3761a03b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 31ffcb82ced81bb75faa800d2b7e883a3761a03b --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=826874ccc97bd7bf541b4875000001d5-1483286196.284817-156018094 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-01-01 11:54:02,753 truncating revision to first 12 chars 2017-01-01 11:54:02,753 Setting DEBUG logging. 2017-01-01 11:54:02,753 attempt 1/10 2017-01-01 11:54:02,754 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/31ffcb82ced8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-01 11:54:03,208 unpacking tar archive at: mozilla-central-31ffcb82ced8/testing/mozharness/ program finished with exit code 0 elapsedTime=0.859423 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-01 11:54:02.776730) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-01 11:54:02.777025) ========= script_repo_revision: 31ffcb82ced81bb75faa800d2b7e883a3761a03b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-01 11:54:02.777685) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-01 11:54:02.777950) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-01 11:54:02.790096) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2017-01-01 11:54:02.790428) ========= /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=826874ccc97bd7bf541b4875000001d5-1483286196.284817-156018094 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:54:03 INFO - MultiFileLogger online at 20170101 11:54:03 in /builds/slave/test 11:54:03 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 11:54:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:54:03 INFO - {'append_to_log': False, 11:54:03 INFO - 'base_work_dir': '/builds/slave/test', 11:54:03 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 11:54:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:54:03 INFO - 'branch': 'Firefox-Non-PGO', 11:54:03 INFO - 'buildbot_json_path': 'buildprops.json', 11:54:03 INFO - 'config_files': ('talos/linux_config.py',), 11:54:03 INFO - 'default_actions': ('clobber', 11:54:03 INFO - 'read-buildbot-config', 11:54:03 INFO - 'download-and-extract', 11:54:03 INFO - 'populate-webroot', 11:54:03 INFO - 'create-virtualenv', 11:54:03 INFO - 'install', 11:54:03 INFO - 'run-tests'), 11:54:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:54:03 INFO - 'download_minidump_stackwalk': True, 11:54:03 INFO - 'download_symbols': 'ondemand', 11:54:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:54:03 INFO - 'tooltool.py': '/tools/tooltool.py', 11:54:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:54:03 INFO - '/tools/misc-python/virtualenv.py')}, 11:54:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:54:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:54:03 INFO - 'installer_path': 'installer.exe', 11:54:03 INFO - 'log_level': 'info', 11:54:03 INFO - 'log_name': 'talos', 11:54:03 INFO - 'log_to_console': True, 11:54:03 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:54:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:54:03 INFO - 'opt_config_files': (), 11:54:03 INFO - 'pip_index': False, 11:54:03 INFO - 'sps_profile': False, 11:54:03 INFO - 'sps_profile_interval': 0, 11:54:03 INFO - 'suite': 'dromaeojs', 11:54:03 INFO - 'system_bits': '32', 11:54:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:54:03 INFO - 'title': 'talos-linux64-ix-052', 11:54:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:54:03 INFO - 'use_talos_json': True, 11:54:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:54:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:54:03 INFO - 'work_dir': 'build'} 11:54:03 INFO - [mozharness: 2017-01-01 19:54:03.741104Z] Running clobber step. 11:54:03 INFO - Running pre-action listener: _resource_record_pre_action 11:54:03 INFO - Running main action method: clobber 11:54:03 INFO - rmtree: /builds/slave/test/build 11:54:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:54:04 INFO - Running post-action listener: _resource_record_post_action 11:54:04 INFO - [mozharness: 2017-01-01 19:54:04.205615Z] Finished clobber step (success) 11:54:04 INFO - [mozharness: 2017-01-01 19:54:04.205717Z] Running read-buildbot-config step. 11:54:04 INFO - Running pre-action listener: _resource_record_pre_action 11:54:04 INFO - Running main action method: read_buildbot_config 11:54:04 INFO - Using buildbot properties: 11:54:04 INFO - { 11:54:04 INFO - "project": "", 11:54:04 INFO - "product": "firefox", 11:54:04 INFO - "who": "philringnalda@gmail.com", 11:54:04 INFO - "installer_path": "public/build/target.tar.bz2", 11:54:04 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 11:54:04 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 11:54:04 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 11:54:04 INFO - "stage_platform": "linux64", 11:54:04 INFO - "basedir": "/builds/slave/test", 11:54:04 INFO - "buildnumber": 761, 11:54:04 INFO - "platform": "ubuntu64_hw", 11:54:04 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 11:54:04 INFO - "slavebuilddir": "test", 11:54:04 INFO - "taskId": "DgtBpkZsRHe8CZ9l8LEBxQ", 11:54:04 INFO - "branch": "mozilla-central", 11:54:04 INFO - "script_repo_revision": "production", 11:54:04 INFO - "revision": "31ffcb82ced81bb75faa800d2b7e883a3761a03b", 11:54:04 INFO - "slavename": "talos-linux64-ix-052", 11:54:04 INFO - "repo_path": "mozilla-central" 11:54:04 INFO - } 11:54:04 INFO - Finding installer, test and symbols from parent task. 11:54:04 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DgtBpkZsRHe8CZ9l8LEBxQ'}, attempt #1 11:54:04 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DgtBpkZsRHe8CZ9l8LEBxQ'}, attempt #1 11:54:05 INFO - Task dependencies: LawvOJPnQIiDuK5Ahz4lag 11:54:05 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LawvOJPnQIiDuK5Ahz4lag'}, attempt #1 11:54:05 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/LawvOJPnQIiDuK5Ahz4lag/artifacts/public/build/target.tar.bz2 11:54:05 INFO - Set test_url: https://queue.taskcluster.net/v1/task/LawvOJPnQIiDuK5Ahz4lag/artifacts/public/build/test_packages.json 11:54:05 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/LawvOJPnQIiDuK5Ahz4lag/artifacts/public/build/target.crashreporter-symbols.zip 11:54:05 INFO - Running post-action listener: _resource_record_post_action 11:54:05 INFO - [mozharness: 2017-01-01 19:54:05.653561Z] Finished read-buildbot-config step (success) 11:54:05 INFO - [mozharness: 2017-01-01 19:54:05.653781Z] Running download-and-extract step. 11:54:05 INFO - Running pre-action listener: _resource_record_pre_action 11:54:05 INFO - Running main action method: download_and_extract 11:54:05 INFO - mkdir: /builds/slave/test/build/tests 11:54: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')]} 11:54:05 INFO - trying https://queue.taskcluster.net/v1/task/LawvOJPnQIiDuK5Ahz4lag/artifacts/public/build/target.test_packages.json 11:54:05 INFO - Downloading https://queue.taskcluster.net/v1/task/LawvOJPnQIiDuK5Ahz4lag/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 11:54:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LawvOJPnQIiDuK5Ahz4lag/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 11:54:06 INFO - Downloaded 896 bytes. 11:54:06 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 11:54:06 INFO - Using the following test package requirements: 11:54:06 INFO - {u'common': [u'target.common.tests.zip'], 11:54:06 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 11:54:06 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 11:54:06 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 11:54:06 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 11:54:06 INFO - u'mozbase': [u'target.common.tests.zip'], 11:54:06 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 11:54:06 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 11:54:06 INFO - u'web-platform': [u'target.common.tests.zip', 11:54:06 INFO - u'target.web-platform.tests.zip'], 11:54:06 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 11:54:06 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 11:54:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LawvOJPnQIiDuK5Ahz4lag/artifacts/public/build/target.common.tests.zip 11:54:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LawvOJPnQIiDuK5Ahz4lag/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:54:06 INFO - Fetch https://queue.taskcluster.net/v1/task/LawvOJPnQIiDuK5Ahz4lag/artifacts/public/build/target.common.tests.zip into memory 11:54:07 INFO - Expected file size: 20246928 11:54:07 INFO - Obtained file size: 20246928 11:54:11 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 11:54:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LawvOJPnQIiDuK5Ahz4lag/artifacts/public/build/target.common.tests.zip 11:54:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LawvOJPnQIiDuK5Ahz4lag/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:54:11 INFO - Fetch https://queue.taskcluster.net/v1/task/LawvOJPnQIiDuK5Ahz4lag/artifacts/public/build/target.common.tests.zip into memory 11:54:11 INFO - Expected file size: 20246928 11:54:11 INFO - Obtained file size: 20246928 11:54:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LawvOJPnQIiDuK5Ahz4lag/artifacts/public/build/target.talos.tests.zip 11:54:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LawvOJPnQIiDuK5Ahz4lag/artifacts/public/build/target.talos.tests.zip'}, attempt #1 11:54:16 INFO - Fetch https://queue.taskcluster.net/v1/task/LawvOJPnQIiDuK5Ahz4lag/artifacts/public/build/target.talos.tests.zip into memory 11:54:17 INFO - Expected file size: 14409776 11:54:17 INFO - Obtained file size: 14409776 11:54:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:54:17 INFO - trying https://queue.taskcluster.net/v1/task/LawvOJPnQIiDuK5Ahz4lag/artifacts/public/build/target.tar.bz2 11:54:17 INFO - Downloading https://queue.taskcluster.net/v1/task/LawvOJPnQIiDuK5Ahz4lag/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 11:54:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LawvOJPnQIiDuK5Ahz4lag/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 11:54:18 INFO - Downloaded 55849969 bytes. 11:54:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LawvOJPnQIiDuK5Ahz4lag/artifacts/public/build/target.tar.bz2 11:54:18 INFO - mkdir: /builds/slave/test/properties 11:54:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:54:18 INFO - Writing to file /builds/slave/test/properties/build_url 11:54:18 INFO - Contents: 11:54:18 INFO - build_url:https://queue.taskcluster.net/v1/task/LawvOJPnQIiDuK5Ahz4lag/artifacts/public/build/target.tar.bz2 11:54:18 INFO - Running post-action listener: _resource_record_post_action 11:54:18 INFO - Running post-action listener: set_extra_try_arguments 11:54:18 INFO - [mozharness: 2017-01-01 19:54:18.391680Z] Finished download-and-extract step (success) 11:54:18 INFO - [mozharness: 2017-01-01 19:54:18.391778Z] Running populate-webroot step. 11:54:18 INFO - Running pre-action listener: _resource_record_pre_action 11:54:18 INFO - Running main action method: populate_webroot 11:54:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:54:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:54:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:54:18 INFO - '--tppagecycles', 11:54:18 INFO - '7'], 11:54:18 INFO - 'tests': ['tsvgm']}}, 11:54:18 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:54:18 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:54:18 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:54:18 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:54:18 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:54:18 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:54:18 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 11:54:18 INFO - 'talos_options': ['--disable-e10s'], 11:54:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:54:18 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:54:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:54:18 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 11:54:18 INFO - 'talos_options': ['--disable-e10s'], 11:54:18 INFO - 'tests': ['damp', 'tps']}, 11:54:18 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:54:18 INFO - 'tests': ['damp', 'tps']}, 11:54:18 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:54:18 INFO - 'tests': ['dromaeo_dom']}, 11:54:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:54:18 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:54:18 INFO - 'tests': ['basic_compositor_video']}, 11:54:18 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:54:18 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:54:18 INFO - 'tests': ['a11yr', 11:54:18 INFO - 'ts_paint', 11:54:18 INFO - 'tpaint', 11:54:18 INFO - 'sessionrestore', 11:54:18 INFO - 'sessionrestore_no_auto_restore', 11:54:18 INFO - 'tabpaint']}, 11:54:18 INFO - 'other-e10s': {'tests': ['a11yr', 11:54:18 INFO - 'ts_paint', 11:54:18 INFO - 'tpaint', 11:54:18 INFO - 'sessionrestore', 11:54:18 INFO - 'sessionrestore_no_auto_restore', 11:54:18 INFO - 'tabpaint']}, 11:54:18 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:54:18 INFO - 'ts_paint', 11:54:18 INFO - 'tpaint', 11:54:18 INFO - 'sessionrestore', 11:54:18 INFO - 'sessionrestore_no_auto_restore', 11:54:18 INFO - 'tabpaint']}, 11:54:18 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:54:18 INFO - 'ts_paint', 11:54:18 INFO - 'tpaint', 11:54:18 INFO - 'sessionrestore', 11:54:18 INFO - 'sessionrestore_no_auto_restore', 11:54:18 INFO - 'tabpaint']}, 11:54:18 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:54:18 INFO - 'tests': ['a11yr', 11:54:18 INFO - 'ts_paint', 11:54:18 INFO - 'tpaint', 11:54:18 INFO - 'sessionrestore', 11:54:18 INFO - 'sessionrestore_no_auto_restore', 11:54:18 INFO - 'tabpaint']}, 11:54:18 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:54:18 INFO - 'tests': ['a11yr', 11:54:18 INFO - 'ts_paint', 11:54:18 INFO - 'tpaint', 11:54:18 INFO - 'sessionrestore', 11:54:18 INFO - 'sessionrestore_no_auto_restore', 11:54:18 INFO - 'tabpaint']}, 11:54:18 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:54:18 INFO - 'tests': ['tsvgx', 11:54:18 INFO - 'tsvgr_opacity', 11:54:18 INFO - 'tart', 11:54:18 INFO - 'tscrollx', 11:54:18 INFO - 'cart', 11:54:18 INFO - 'tsvg_static']}, 11:54:18 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:54:18 INFO - 'tsvgr_opacity', 11:54:18 INFO - 'tart', 11:54:18 INFO - 'tscrollx', 11:54:18 INFO - 'cart', 11:54:18 INFO - 'tsvg_static']}, 11:54:18 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 11:54:18 INFO - 'talos_options': ['--disable-e10s'], 11:54:18 INFO - 'tests': ['tp5o']}, 11:54:18 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:54:18 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 11:54:18 INFO - 'talos_options': ['--disable-e10s', 11:54:18 INFO - '--xperf_path', 11:54:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:54:18 INFO - 'tests': ['tp5n']}, 11:54:18 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:54:18 INFO - 'talos_options': ['--xperf_path', 11:54:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:54:18 INFO - 'tests': ['tp5n']}}, 11:54:18 INFO - 'talos.zip': {'path': '', 11:54:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:54:18 INFO - Running post-action listener: _resource_record_post_action 11:54:18 INFO - [mozharness: 2017-01-01 19:54:18.404955Z] Finished populate-webroot step (success) 11:54:18 INFO - [mozharness: 2017-01-01 19:54:18.405049Z] Running create-virtualenv step. 11:54:18 INFO - Running pre-action listener: _resource_record_pre_action 11:54:18 INFO - Running main action method: create_virtualenv 11:54:18 INFO - Creating virtualenv /builds/slave/test/build/venv 11:54:18 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 11:54:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:54:18 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:54:18 INFO - Using real prefix '/usr' 11:54:18 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:54:19 INFO - Installing distribute.............................................................................................................................................................................................done. 11:54:20 INFO - Installing pip.................done. 11:54:20 INFO - Return code: 0 11:54:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:54:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:54:21 INFO - Reading from file tmpfile_stdout 11:54:21 INFO - Output received: 11:54:21 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) 11:54:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:54: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')]} 11:54:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:54:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:54: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')]} 11:54:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:54:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:54:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x215b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x226ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24cd630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2556850>, '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 0x24cb6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24cbb60>, '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': '826874ccc97bd7bf541b4875000001d5-1483286196.284817-156018094', '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 11:54:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 11:54:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:54:21 INFO - Using env: {'DISPLAY': ':0', 11:54:21 INFO - 'HOME': '/home/cltbld', 11:54:21 INFO - 'LANG': 'en_US.UTF-8', 11:54:21 INFO - 'LANGUAGE': 'en_US:en', 11:54:21 INFO - 'LOGNAME': 'cltbld', 11:54:21 INFO - 'MAIL': '/var/mail/cltbld', 11:54:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:54:21 INFO - 'MOZ_NO_REMOTE': '1', 11:54:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:54:21 INFO - 'NO_EM_RESTART': '1', 11:54:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:54:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:54:21 INFO - 'PWD': '/builds/slave/test', 11:54:21 INFO - 'SHELL': '/bin/bash', 11:54:21 INFO - 'SHLVL': '1', 11:54:21 INFO - 'TERM': 'linux', 11:54:21 INFO - 'TMOUT': '86400', 11:54:21 INFO - 'USER': 'cltbld', 11:54:21 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1483286196.284817-156018094', 11:54:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:54:21 INFO - '_': '/tools/buildbot/bin/python'} 11:54:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:54:21 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 11:54:21 INFO - Cleaning up... 11:54:21 INFO - Return code: 0 11:54:21 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:54: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')]} 11:54:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:54:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:54: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')]} 11:54:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:54:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:54:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x215b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x226ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24cd630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2556850>, '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 0x24cb6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24cbb60>, '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': '826874ccc97bd7bf541b4875000001d5-1483286196.284817-156018094', '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 11:54:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 11:54:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:54:21 INFO - Using env: {'DISPLAY': ':0', 11:54:21 INFO - 'HOME': '/home/cltbld', 11:54:21 INFO - 'LANG': 'en_US.UTF-8', 11:54:21 INFO - 'LANGUAGE': 'en_US:en', 11:54:21 INFO - 'LOGNAME': 'cltbld', 11:54:21 INFO - 'MAIL': '/var/mail/cltbld', 11:54:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:54:21 INFO - 'MOZ_NO_REMOTE': '1', 11:54:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:54:21 INFO - 'NO_EM_RESTART': '1', 11:54:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:54:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:54:21 INFO - 'PWD': '/builds/slave/test', 11:54:21 INFO - 'SHELL': '/bin/bash', 11:54:21 INFO - 'SHLVL': '1', 11:54:21 INFO - 'TERM': 'linux', 11:54:21 INFO - 'TMOUT': '86400', 11:54:21 INFO - 'USER': 'cltbld', 11:54:21 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1483286196.284817-156018094', 11:54:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:54:21 INFO - '_': '/tools/buildbot/bin/python'} 11:54:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:54:21 INFO - Downloading/unpacking psutil>=3.1.1 11:54:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:54:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:54:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:54:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:54:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:54:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:54:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:54:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:54:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:54:23 INFO - Installing collected packages: psutil 11:54:23 INFO - Running setup.py install for psutil 11:54:23 INFO - building 'psutil._psutil_linux' extension 11:54:23 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 11:54:23 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 11:54:24 INFO - building 'psutil._psutil_posix' extension 11:54:24 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 11:54:24 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 11:54:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:54:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:54:24 INFO - Successfully installed psutil 11:54:24 INFO - Cleaning up... 11:54:24 INFO - Return code: 0 11:54:24 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:54:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:54:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:54:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:54:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:54:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:54:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:54:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x215b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x226ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24cd630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2556850>, '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 0x24cb6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24cbb60>, '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': '826874ccc97bd7bf541b4875000001d5-1483286196.284817-156018094', '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 11:54:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:54:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:54:24 INFO - Using env: {'DISPLAY': ':0', 11:54:24 INFO - 'HOME': '/home/cltbld', 11:54:24 INFO - 'LANG': 'en_US.UTF-8', 11:54:24 INFO - 'LANGUAGE': 'en_US:en', 11:54:24 INFO - 'LOGNAME': 'cltbld', 11:54:24 INFO - 'MAIL': '/var/mail/cltbld', 11:54:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:54:24 INFO - 'MOZ_NO_REMOTE': '1', 11:54:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:54:24 INFO - 'NO_EM_RESTART': '1', 11:54:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:54:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:54:24 INFO - 'PWD': '/builds/slave/test', 11:54:24 INFO - 'SHELL': '/bin/bash', 11:54:24 INFO - 'SHLVL': '1', 11:54:24 INFO - 'TERM': 'linux', 11:54:24 INFO - 'TMOUT': '86400', 11:54:24 INFO - 'USER': 'cltbld', 11:54:24 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1483286196.284817-156018094', 11:54:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:54:24 INFO - '_': '/tools/buildbot/bin/python'} 11:54:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:54:24 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:54:24 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 11:54:24 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 11:54:24 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 11:54:24 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 11:54:24 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 11:54:24 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 11:54:26 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:54:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:54:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:54:26 INFO - Installing collected packages: mozsystemmonitor 11:54:26 INFO - Running setup.py install for mozsystemmonitor 11:54:26 INFO - Successfully installed mozsystemmonitor 11:54:26 INFO - Cleaning up... 11:54:26 INFO - Return code: 0 11:54:26 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:54:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:54:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:54:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:54:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:54:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:54:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:54:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x215b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x226ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24cd630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2556850>, '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 0x24cb6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24cbb60>, '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': '826874ccc97bd7bf541b4875000001d5-1483286196.284817-156018094', '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 11:54:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 11:54:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 11:54:26 INFO - Using env: {'DISPLAY': ':0', 11:54:26 INFO - 'HOME': '/home/cltbld', 11:54:26 INFO - 'LANG': 'en_US.UTF-8', 11:54:26 INFO - 'LANGUAGE': 'en_US:en', 11:54:26 INFO - 'LOGNAME': 'cltbld', 11:54:26 INFO - 'MAIL': '/var/mail/cltbld', 11:54:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:54:26 INFO - 'MOZ_NO_REMOTE': '1', 11:54:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:54:26 INFO - 'NO_EM_RESTART': '1', 11:54:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:54:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:54:26 INFO - 'PWD': '/builds/slave/test', 11:54:26 INFO - 'SHELL': '/bin/bash', 11:54:26 INFO - 'SHLVL': '1', 11:54:26 INFO - 'TERM': 'linux', 11:54:26 INFO - 'TMOUT': '86400', 11:54:26 INFO - 'USER': 'cltbld', 11:54:26 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1483286196.284817-156018094', 11:54:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:54:26 INFO - '_': '/tools/buildbot/bin/python'} 11:54:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:54:26 INFO - Downloading/unpacking jsonschema==2.5.1 11:54:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:54:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:54:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:54:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:54:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:54:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:54:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:54:28 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:54:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:54:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:54:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:54:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:54:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:54:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:54:29 INFO - Downloading functools32-3.2.3-2.tar.gz 11:54:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:54:29 INFO - warning: no files found matching '*.txt' 11:54:29 INFO - no previously-included directories found matching 'build' 11:54:29 INFO - no previously-included directories found matching 'dist' 11:54:29 INFO - no previously-included directories found matching '.git*' 11:54:29 INFO - Installing collected packages: jsonschema, functools32 11:54:29 INFO - Running setup.py install for functools32 11:54:29 INFO - warning: no files found matching '*.txt' 11:54:29 INFO - no previously-included directories found matching 'build' 11:54:29 INFO - no previously-included directories found matching 'dist' 11:54:29 INFO - no previously-included directories found matching '.git*' 11:54:29 INFO - Successfully installed jsonschema functools32 11:54:29 INFO - Cleaning up... 11:54:29 INFO - Return code: 0 11:54:29 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:54:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:54:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:54:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:54:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:54:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:54:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:54:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x215b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x226ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24cd630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2556850>, '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 0x24cb6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24cbb60>, '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': '826874ccc97bd7bf541b4875000001d5-1483286196.284817-156018094', '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 11:54:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 11:54:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 11:54:29 INFO - Using env: {'DISPLAY': ':0', 11:54:29 INFO - 'HOME': '/home/cltbld', 11:54:29 INFO - 'LANG': 'en_US.UTF-8', 11:54:29 INFO - 'LANGUAGE': 'en_US:en', 11:54:29 INFO - 'LOGNAME': 'cltbld', 11:54:29 INFO - 'MAIL': '/var/mail/cltbld', 11:54:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:54:29 INFO - 'MOZ_NO_REMOTE': '1', 11:54:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:54:29 INFO - 'NO_EM_RESTART': '1', 11:54:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:54:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:54:29 INFO - 'PWD': '/builds/slave/test', 11:54:29 INFO - 'SHELL': '/bin/bash', 11:54:29 INFO - 'SHLVL': '1', 11:54:29 INFO - 'TERM': 'linux', 11:54:29 INFO - 'TMOUT': '86400', 11:54:29 INFO - 'USER': 'cltbld', 11:54:29 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1483286196.284817-156018094', 11:54:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:54:29 INFO - '_': '/tools/buildbot/bin/python'} 11:54:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:54:29 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:54:29 INFO - Cleaning up... 11:54:29 INFO - Return code: 0 11:54:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:54:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:54:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:54:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:54:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:54:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:54:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:54:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x215b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x226ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24cd630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2556850>, '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 0x24cb6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24cbb60>, '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': '826874ccc97bd7bf541b4875000001d5-1483286196.284817-156018094', '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 11:54:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:54:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:54:29 INFO - Using env: {'DISPLAY': ':0', 11:54:29 INFO - 'HOME': '/home/cltbld', 11:54:29 INFO - 'LANG': 'en_US.UTF-8', 11:54:29 INFO - 'LANGUAGE': 'en_US:en', 11:54:29 INFO - 'LOGNAME': 'cltbld', 11:54:29 INFO - 'MAIL': '/var/mail/cltbld', 11:54:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:54:29 INFO - 'MOZ_NO_REMOTE': '1', 11:54:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:54:29 INFO - 'NO_EM_RESTART': '1', 11:54:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:54:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:54:29 INFO - 'PWD': '/builds/slave/test', 11:54:29 INFO - 'SHELL': '/bin/bash', 11:54:29 INFO - 'SHLVL': '1', 11:54:29 INFO - 'TERM': 'linux', 11:54:29 INFO - 'TMOUT': '86400', 11:54:29 INFO - 'USER': 'cltbld', 11:54:29 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1483286196.284817-156018094', 11:54:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:54:29 INFO - '_': '/tools/buildbot/bin/python'} 11:54:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:54:29 INFO - Downloading/unpacking blobuploader==1.2.4 11:54:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:54:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:54:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:54:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:54:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:54:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:54:31 INFO - Downloading blobuploader-1.2.4.tar.gz 11:54:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:54:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:54:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:54:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:54:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:54:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:54:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:54:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:54:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:54:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:54:32 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 11:54:32 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 11:54:32 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 11:54:32 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 11:54:32 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 11:54:32 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 11:54:32 INFO - Downloading docopt-0.6.1.tar.gz 11:54:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:54:32 INFO - Installing collected packages: blobuploader, requests, docopt 11:54:32 INFO - Running setup.py install for blobuploader 11:54:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:54:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:54:32 INFO - Running setup.py install for requests 11:54:33 INFO - Running setup.py install for docopt 11:54:33 INFO - Successfully installed blobuploader requests docopt 11:54:33 INFO - Cleaning up... 11:54:33 INFO - Return code: 0 11:54:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:54:33 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')]} 11:54:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:54:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:54:33 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')]} 11:54:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:54:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:54:33 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 0x215b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x226ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24cd630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2556850>, '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 0x24cb6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24cbb60>, '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': '826874ccc97bd7bf541b4875000001d5-1483286196.284817-156018094', '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 11:54:33 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 11:54:33 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 11:54:33 INFO - Using env: {'DISPLAY': ':0', 11:54:33 INFO - 'HOME': '/home/cltbld', 11:54:33 INFO - 'LANG': 'en_US.UTF-8', 11:54:33 INFO - 'LANGUAGE': 'en_US:en', 11:54:33 INFO - 'LOGNAME': 'cltbld', 11:54:33 INFO - 'MAIL': '/var/mail/cltbld', 11:54:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:54:33 INFO - 'MOZ_NO_REMOTE': '1', 11:54:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:54:33 INFO - 'NO_EM_RESTART': '1', 11:54:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:54:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:54:33 INFO - 'PWD': '/builds/slave/test', 11:54:33 INFO - 'SHELL': '/bin/bash', 11:54:33 INFO - 'SHLVL': '1', 11:54:33 INFO - 'TERM': 'linux', 11:54:33 INFO - 'TMOUT': '86400', 11:54:33 INFO - 'USER': 'cltbld', 11:54:33 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1483286196.284817-156018094', 11:54:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:54:33 INFO - '_': '/tools/buildbot/bin/python'} 11:54:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:54:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:54:33 INFO - Running setup.py (path:/tmp/pip-AwRBj2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:54:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:54:33 INFO - Running setup.py (path:/tmp/pip-n27FUL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:54:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:54:33 INFO - Running setup.py (path:/tmp/pip-b5jlOF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:54:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:54:33 INFO - Running setup.py (path:/tmp/pip-qk2VlN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:54:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:54:33 INFO - Running setup.py (path:/tmp/pip-LMXq3i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:54:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:54:33 INFO - Running setup.py (path:/tmp/pip-4wJOrs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:54:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:54:33 INFO - Running setup.py (path:/tmp/pip-OSMASj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:54:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:54:33 INFO - Running setup.py (path:/tmp/pip-qCb_Ve-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:54:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:54:33 INFO - Running setup.py (path:/tmp/pip-8f67ki-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:54:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:54:34 INFO - Running setup.py (path:/tmp/pip-jCTPet-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:54:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:54:34 INFO - Running setup.py (path:/tmp/pip-eE_gb4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:54:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:54:34 INFO - Running setup.py (path:/tmp/pip-5k6Thx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:54:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:54:34 INFO - Running setup.py (path:/tmp/pip-l2ZCay-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:54:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:54:34 INFO - Running setup.py (path:/tmp/pip-JGQHvj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:54:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:54:34 INFO - Running setup.py (path:/tmp/pip-H1vkxf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:54:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:54:34 INFO - Running setup.py (path:/tmp/pip-UNkdSS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:54:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:54:34 INFO - Running setup.py (path:/tmp/pip-Vyv7ID-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:54:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:54:34 INFO - Running setup.py install for manifestparser 11:54:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:54:34 INFO - Running setup.py install for mozcrash 11:54:34 INFO - Running setup.py install for mozdebug 11:54:34 INFO - Running setup.py install for mozdevice 11:54:35 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:54:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:54:35 INFO - Running setup.py install for mozfile 11:54:35 INFO - Running setup.py install for mozhttpd 11:54:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:54:35 INFO - Running setup.py install for mozinfo 11:54:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:54:35 INFO - Running setup.py install for mozInstall 11:54:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:54:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:54:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:54:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:54:35 INFO - Running setup.py install for mozleak 11:54:35 INFO - Running setup.py install for mozlog 11:54:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:54:35 INFO - Running setup.py install for moznetwork 11:54:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:54:35 INFO - Running setup.py install for mozprocess 11:54:35 INFO - Running setup.py install for mozprofile 11:54:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:54:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:54:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:54:36 INFO - Running setup.py install for mozrunner 11:54:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:54:36 INFO - Running setup.py install for mozscreenshot 11:54:36 INFO - Running setup.py install for moztest 11:54:36 INFO - Running setup.py install for mozversion 11:54:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:54:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:54:36 INFO - Cleaning up... 11:54:36 INFO - Return code: 0 11:54:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:54:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:54:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:54:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:54:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:54:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:54:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:54:36 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 0x215b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x226ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24cd630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2556850>, '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 0x24cb6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24cbb60>, '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': '826874ccc97bd7bf541b4875000001d5-1483286196.284817-156018094', '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 11:54:36 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 11:54:36 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 11:54:36 INFO - Using env: {'DISPLAY': ':0', 11:54:36 INFO - 'HOME': '/home/cltbld', 11:54:36 INFO - 'LANG': 'en_US.UTF-8', 11:54:36 INFO - 'LANGUAGE': 'en_US:en', 11:54:36 INFO - 'LOGNAME': 'cltbld', 11:54:36 INFO - 'MAIL': '/var/mail/cltbld', 11:54:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:54:36 INFO - 'MOZ_NO_REMOTE': '1', 11:54:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:54:36 INFO - 'NO_EM_RESTART': '1', 11:54:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:54:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:54:36 INFO - 'PWD': '/builds/slave/test', 11:54:36 INFO - 'SHELL': '/bin/bash', 11:54:36 INFO - 'SHLVL': '1', 11:54:36 INFO - 'TERM': 'linux', 11:54:36 INFO - 'TMOUT': '86400', 11:54:36 INFO - 'USER': 'cltbld', 11:54:36 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1483286196.284817-156018094', 11:54:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:54:36 INFO - '_': '/tools/buildbot/bin/python'} 11:54:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:54:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:54:36 INFO - Running setup.py (path:/tmp/pip-76Ize3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:54:36 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)) 11:54:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:54:36 INFO - Running setup.py (path:/tmp/pip-1AZQGf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:54:36 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)) 11:54:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:54:36 INFO - Running setup.py (path:/tmp/pip-7G_aeJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:54:36 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)) 11:54:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:54:36 INFO - Running setup.py (path:/tmp/pip-N3aT9t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:54:36 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)) 11:54:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:54:36 INFO - Running setup.py (path:/tmp/pip-x2anY9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:54:37 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)) 11:54:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:54:37 INFO - Running setup.py (path:/tmp/pip-e_C_9Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:54:37 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)) 11:54:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:54:37 INFO - Running setup.py (path:/tmp/pip-cZ6NTy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:54:37 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)) 11:54:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:54:37 INFO - Running setup.py (path:/tmp/pip-O80Rje-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:54:37 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)) 11:54:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:54:37 INFO - Running setup.py (path:/tmp/pip-Wud0yr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:54:37 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)) 11:54:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:54:37 INFO - Running setup.py (path:/tmp/pip-9UEPgY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:54:37 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)) 11:54:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:54:37 INFO - Running setup.py (path:/tmp/pip-Yl38Sn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:54:37 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)) 11:54:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:54:37 INFO - Running setup.py (path:/tmp/pip-SSKzpk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:54:37 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)) 11:54:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:54:37 INFO - Running setup.py (path:/tmp/pip-G3cEjH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:54:37 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)) 11:54:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:54:37 INFO - Running setup.py (path:/tmp/pip-vGfofm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:54:37 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)) 11:54:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:54:37 INFO - Running setup.py (path:/tmp/pip-g5_Crj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:54:37 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)) 11:54:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:54:37 INFO - Running setup.py (path:/tmp/pip-ARc4wz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:54:37 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)) 11:54:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:54:37 INFO - Running setup.py (path:/tmp/pip-sAOrKV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:54:37 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)) 11:54:37 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)) 11:54:37 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)) 11:54:37 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)) 11:54:37 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)) 11:54:37 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)) 11:54:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:54:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:54:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:54:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:54:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:54:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:54:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:54:39 INFO - Downloading blessings-1.6.tar.gz 11:54:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:54:40 INFO - Installing collected packages: blessings 11:54:40 INFO - Running setup.py install for blessings 11:54:40 INFO - Successfully installed blessings 11:54:40 INFO - Cleaning up... 11:54:40 INFO - Return code: 0 11:54:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:54:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:54:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:54:40 INFO - Reading from file tmpfile_stdout 11:54:40 INFO - Current package versions: 11:54:40 INFO - argparse == 1.2.1 11:54:40 INFO - blessings == 1.6 11:54:40 INFO - blobuploader == 1.2.4 11:54:40 INFO - docopt == 0.6.1 11:54:40 INFO - functools32 == 3.2.3-2 11:54:40 INFO - jsonschema == 2.5.1 11:54:40 INFO - manifestparser == 1.1 11:54:40 INFO - mozInstall == 1.12 11:54:40 INFO - mozcrash == 1.0 11:54:40 INFO - mozdebug == 0.1 11:54:40 INFO - mozdevice == 0.49 11:54:40 INFO - mozfile == 1.2 11:54:40 INFO - mozhttpd == 0.7 11:54:40 INFO - mozinfo == 0.9 11:54:40 INFO - mozleak == 0.1 11:54:40 INFO - mozlog == 3.4 11:54:40 INFO - moznetwork == 0.27 11:54:40 INFO - mozprocess == 0.23 11:54:40 INFO - mozprofile == 0.28 11:54:40 INFO - mozrunner == 6.13 11:54:40 INFO - mozscreenshot == 0.1 11:54:40 INFO - mozsystemmonitor == 0.3 11:54:40 INFO - moztest == 0.8 11:54:40 INFO - mozversion == 1.4 11:54:40 INFO - psutil == 3.1.1 11:54:40 INFO - requests == 1.2.3 11:54:40 INFO - wsgiref == 0.1.2 11:54:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:54:40 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')]} 11:54:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:54:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:54:40 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')]} 11:54:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:54:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:54:40 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 0x215b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x226ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24cd630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2556850>, '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 0x24cb6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24cbb60>, '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': '826874ccc97bd7bf541b4875000001d5-1483286196.284817-156018094', '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 11:54:40 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 11:54:40 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 11:54:40 INFO - Using env: {'DISPLAY': ':0', 11:54:40 INFO - 'HOME': '/home/cltbld', 11:54:40 INFO - 'LANG': 'en_US.UTF-8', 11:54:40 INFO - 'LANGUAGE': 'en_US:en', 11:54:40 INFO - 'LOGNAME': 'cltbld', 11:54:40 INFO - 'MAIL': '/var/mail/cltbld', 11:54:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:54:40 INFO - 'MOZ_NO_REMOTE': '1', 11:54:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:54:40 INFO - 'NO_EM_RESTART': '1', 11:54:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:54:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:54:40 INFO - 'PWD': '/builds/slave/test', 11:54:40 INFO - 'SHELL': '/bin/bash', 11:54:40 INFO - 'SHLVL': '1', 11:54:40 INFO - 'TERM': 'linux', 11:54:40 INFO - 'TMOUT': '86400', 11:54:40 INFO - 'USER': 'cltbld', 11:54:40 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1483286196.284817-156018094', 11:54:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:54:40 INFO - '_': '/tools/buildbot/bin/python'} 11:54:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:54:40 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)) 11:54:40 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)) 11:54:40 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)) 11:54:40 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)) 11:54:40 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)) 11:54:40 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)) 11:54:40 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)) 11:54:40 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)) 11:54:40 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)) 11:54:40 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)) 11:54:40 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)) 11:54:40 INFO - Cleaning up... 11:54:40 INFO - Return code: 0 11:54:40 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:54:40 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')]} 11:54:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:54:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:54:40 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')]} 11:54:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:54:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:54:40 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 0x215b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x226ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24cd630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2556850>, '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 0x24cb6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24cbb60>, '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': '826874ccc97bd7bf541b4875000001d5-1483286196.284817-156018094', '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 11:54:40 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 11:54:40 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 11:54:40 INFO - Using env: {'DISPLAY': ':0', 11:54:40 INFO - 'HOME': '/home/cltbld', 11:54:40 INFO - 'LANG': 'en_US.UTF-8', 11:54:40 INFO - 'LANGUAGE': 'en_US:en', 11:54:40 INFO - 'LOGNAME': 'cltbld', 11:54:40 INFO - 'MAIL': '/var/mail/cltbld', 11:54:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:54:40 INFO - 'MOZ_NO_REMOTE': '1', 11:54:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:54:40 INFO - 'NO_EM_RESTART': '1', 11:54:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:54:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:54:40 INFO - 'PWD': '/builds/slave/test', 11:54:40 INFO - 'SHELL': '/bin/bash', 11:54:40 INFO - 'SHLVL': '1', 11:54:40 INFO - 'TERM': 'linux', 11:54:40 INFO - 'TMOUT': '86400', 11:54:40 INFO - 'USER': 'cltbld', 11:54:40 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1483286196.284817-156018094', 11:54:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:54:40 INFO - '_': '/tools/buildbot/bin/python'} 11:54:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:54:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:54:40 INFO - Cleaning up... 11:54:40 INFO - Return code: 0 11:54:40 INFO - Running post-action listener: _resource_record_post_action 11:54:40 INFO - Running post-action listener: _start_resource_monitoring 11:54:40 INFO - Starting resource monitoring. 11:54:40 INFO - [mozharness: 2017-01-01 19:54:40.886789Z] Finished create-virtualenv step (success) 11:54:40 INFO - [mozharness: 2017-01-01 19:54:40.887250Z] Running install step. 11:54:40 INFO - Running pre-action listener: _resource_record_pre_action 11:54:40 INFO - Running main action method: install 11:54:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:54:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:54:41 INFO - Reading from file tmpfile_stdout 11:54:41 INFO - Detecting whether we're running mozinstall >=1.0... 11:54:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:54:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:54:41 INFO - Reading from file tmpfile_stdout 11:54:41 INFO - Output received: 11:54:41 INFO - Usage: mozinstall [options] installer 11:54:41 INFO - Options: 11:54:41 INFO - -h, --help show this help message and exit 11:54:41 INFO - -d DEST, --destination=DEST 11:54:41 INFO - Directory to install application into. [default: 11:54:41 INFO - "/builds/slave/test"] 11:54:41 INFO - --app=APP Application being installed. [default: firefox] 11:54:41 INFO - mkdir: /builds/slave/test/build/application 11:54:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 11:54:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 11:54:57 INFO - Reading from file tmpfile_stdout 11:54:57 INFO - Output received: 11:54:57 INFO - /builds/slave/test/build/application/firefox/firefox 11:54:57 INFO - Running post-action listener: _resource_record_post_action 11:54:57 INFO - [mozharness: 2017-01-01 19:54:57.865951Z] Finished install step (success) 11:54:57 INFO - [mozharness: 2017-01-01 19:54:57.866226Z] Running run-tests step. 11:54:57 INFO - Running pre-action listener: _resource_record_pre_action 11:54:57 INFO - Running main action method: run_tests 11:54:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:54:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:54:57 INFO - Python 2.7.3 11:54:57 INFO - Return code: 0 11:54:57 INFO - grabbing minidump binary from tooltool 11:54: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')]} 11:54:57 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 0x2556850>, '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 0x24cb6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24cbb60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:54:57 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 11:54:57 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 11:54:57 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 11:54:57 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:54:57 INFO - Return code: 0 11:54:57 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:54:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:54:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:54:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:54:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:54:57 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/LawvOJPnQIiDuK5Ahz4lag/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-052', '--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 11:54:57 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/LawvOJPnQIiDuK5Ahz4lag/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-052 --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 11:54:57 INFO - Using env: {'DISPLAY': ':0', 11:54:57 INFO - 'HOME': '/home/cltbld', 11:54:57 INFO - 'LANG': 'en_US.UTF-8', 11:54:57 INFO - 'LANGUAGE': 'en_US:en', 11:54:57 INFO - 'LOGNAME': 'cltbld', 11:54:57 INFO - 'MAIL': '/var/mail/cltbld', 11:54:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:54:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:54:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:54:57 INFO - 'MOZ_NO_REMOTE': '1', 11:54:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:54:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:54:57 INFO - 'NO_EM_RESTART': '1', 11:54:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:54:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:54:57 INFO - 'PWD': '/builds/slave/test', 11:54:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:54:57 INFO - 'SHELL': '/bin/bash', 11:54:57 INFO - 'SHLVL': '1', 11:54:57 INFO - 'TERM': 'linux', 11:54:57 INFO - 'TMOUT': '86400', 11:54:57 INFO - 'USER': 'cltbld', 11:54:57 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1483286196.284817-156018094', 11:54:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:54:57 INFO - '_': '/tools/buildbot/bin/python'} 11:54:57 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/LawvOJPnQIiDuK5Ahz4lag/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-052', '--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 11:54:58 INFO - mozversion application_buildid: 20170101191715 11:54:58 INFO - mozversion application_changeset: 31ffcb82ced81bb75faa800d2b7e883a3761a03b 11:54:58 INFO - mozversion application_display_name: Nightly 11:54:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:54:58 INFO - mozversion application_name: Firefox 11:54:58 INFO - mozversion application_remotingname: firefox 11:54:58 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:54:58 INFO - mozversion application_vendor: Mozilla 11:54:58 INFO - mozversion application_version: 53.0a1 11:54:58 INFO - mozversion platform_buildid: 20170101191715 11:54:58 INFO - mozversion platform_changeset: 31ffcb82ced81bb75faa800d2b7e883a3761a03b 11:54:58 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:54:58 INFO - mozversion platform_version: 53.0a1 11:54:58 INFO - using testdate: 1483300498 11:54:58 INFO - actual date: 1483300498 11:54:58 INFO - starting webserver on 'localhost:48615' 11:54:58 INFO - SUITE-START | Running 2 tests 11:54:58 INFO - TEST-START | dromaeo_css 11:54:58 INFO - operating with platform_type : linux_ 11:54:58 INFO - Initialising browser for dromaeo_css test... 11:54:58 INFO - TEST-INFO | started process 13828 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpREd8Co/profile http://localhost:48615/getInfo.html) 11:54:58 INFO - PROCESS | 13828 | 1483300498636 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 11:55:02 INFO - PROCESS | 13828 | __metrics Screen width/height:1600/1200 11:55:02 INFO - PROCESS | 13828 | colorDepth:24 11:55:02 INFO - PROCESS | 13828 | Browser inner width/height: 1024/697 11:55:02 INFO - PROCESS | 13828 | __metrics 11:55:03 INFO - PROCESS | 13828 | 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" 11:55:03 INFO - PROCESS | 13828 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:55:03 INFO - PROCESS | 13828 | console.error: PushService: 11:55:03 INFO - PROCESS | 13828 | stateChangeProcessEnqueue: Error transitioning state 11:55:03 INFO - PROCESS | 13828 | UnknownError 11:55:04 INFO - TEST-INFO | 13828: exit 0 11:55:04 INFO - Browser initialized. 11:55:04 INFO - Running cycle 1/1 for dromaeo_css test... 11:55:04 INFO - TEST-INFO | started process 13910 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpREd8Co/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:55:16 INFO - PROCESS | 13910 | RSS: Main: 151556096 11:55:16 INFO - PROCESS | 13910 | 11:56:07 INFO - PROCESS | 13910 | 1483300567943 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 11:58:21 INFO - PROCESS | 13910 | Cycle 1(1): loaded http://localhost:48615/tests/dromaeo/cssquery-dojo.html (next: http://localhost:48615/tests/dromaeo/cssquery-ext.html) 11:58:22 INFO - PROCESS | 13910 | RSS: Main: 251809792 11:58:22 INFO - PROCESS | 13910 | 12:00:05 INFO - PROCESS | 13910 | ************************* 12:00:05 INFO - PROCESS | 13910 | A coding exception was thrown and uncaught in a Task. 12:00:05 INFO - PROCESS | 13910 | 12:00:05 INFO - PROCESS | 13910 | Full message: ReferenceError: fetch is not defined 12:00:05 INFO - PROCESS | 13910 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 12:00:05 INFO - PROCESS | 13910 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 12:00:05 INFO - PROCESS | 13910 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 12:00:05 INFO - PROCESS | 13910 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 12:00:05 INFO - PROCESS | 13910 | TaskImpl@resource://gre/modules/Task.jsm:277:3 12:00:05 INFO - PROCESS | 13910 | asyncFunction@resource://gre/modules/Task.jsm:252:14 12:00:05 INFO - PROCESS | 13910 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 12:00:05 INFO - PROCESS | 13910 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 12:00:05 INFO - PROCESS | 13910 | TaskImpl@resource://gre/modules/Task.jsm:277:3 12:00:05 INFO - PROCESS | 13910 | asyncFunction@resource://gre/modules/Task.jsm:252:14 12:00:05 INFO - PROCESS | 13910 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 12:00:05 INFO - PROCESS | 13910 | 12:00:05 INFO - PROCESS | 13910 | ************************* 12:01:25 INFO - PROCESS | 13910 | Cycle 1(1): loaded http://localhost:48615/tests/dromaeo/cssquery-ext.html (next: http://localhost:48615/tests/dromaeo/cssquery-jquery.html) 12:01:25 INFO - PROCESS | 13910 | RSS: Main: 234381312 12:01:25 INFO - PROCESS | 13910 | 12:04:28 INFO - PROCESS | 13910 | Cycle 1(1): loaded http://localhost:48615/tests/dromaeo/cssquery-jquery.html (next: http://localhost:48615/tests/dromaeo/cssquery-mootools.html) 12:04:28 INFO - PROCESS | 13910 | RSS: Main: 184152064 12:04:28 INFO - PROCESS | 13910 | 12:07:30 INFO - PROCESS | 13910 | Cycle 1(1): loaded http://localhost:48615/tests/dromaeo/cssquery-mootools.html (next: http://localhost:48615/tests/dromaeo/cssquery-prototype.html) 12:07:31 INFO - PROCESS | 13910 | RSS: Main: 213393408 12:07:31 INFO - PROCESS | 13910 | 12:10:34 INFO - PROCESS | 13910 | Cycle 1(1): loaded http://localhost:48615/tests/dromaeo/cssquery-prototype.html (next: http://localhost:48615/tests/dromaeo/cssquery-yui.html) 12:10:35 INFO - PROCESS | 13910 | RSS: Main: 185733120 12:10:35 INFO - PROCESS | 13910 | 12:13:38 INFO - PROCESS | 13910 | Cycle 1(1): loaded http://localhost:48615/tests/dromaeo/cssquery-yui.html (next: http://localhost:48615/tests/dromaeo/cssquery-dojo.html) 12:13:38 INFO - PROCESS | 13910 | RSS: Main: 276193280 12:13:38 INFO - PROCESS | 13910 | 12:13:38 INFO - PROCESS | 13910 | __start_tp_report 12:13:38 INFO - PROCESS | 13910 | _x_x_mozilla_page_load 12:13:38 INFO - PROCESS | 13910 | _x_x_mozilla_page_load_details 12:13:38 INFO - PROCESS | 13910 | |i|pagename|runs| 12:13:38 INFO - PROCESS | 13910 | |0;dojo.html;2555.718870924197;2569.177863083813;2583.8062145339104;2589.5079934812375;2605.92182234533;4724.1260366832075;4755.667103302771;4771.26045462953;4796.232602783555;4805.903881922364;4759.952400475994;4772.164871147544;4797.52024797521;4815.109204797115;4820.180569303218;4361.105973275487;4384.758838263894;4399.802008909591;4424.40270563473;4510.165619360412;4393.934090988621;4409.669274804386;4426.645868330542;4431.335299705038;4474.194644963905;4355.956440435619;4367.279398899178;4434.22401079813;4462.955370446286;4484.372187893698;4403.2954727243805;4416.514183439851;4424.380586717862;4426.026274219666;4430.113977204572;4397.516273209976;4399.956000439986;4408.603225709701;4411.536788637211;4413.139437809628;4711;4721.3390125382475;4777.641676874214;4795.6882802618065;4798.040391921595;5104.719240441774;5105.591552675777;5112.386513618352;5158.200478925773;5161.406438259573;5135.897282054375;5143.254228136937;5160.690358578459;5166.793328266903;5185.740712964337;4611.469680986729;4613.238815595456;4646;4731.1720448921305;4786.353842231324;5092.6180536459915;5112.795488180505;5119.539241468211;5125.384953805567;5166.2508936204795;4850.6362022847725;4873.780679869415;4882.389701287339;4897.461279259279;4936.7284799336385;4966.627502937295;4982.10322142018;4982.8754281143265;4986.90026199474;4993.525615066563;4959.231319145466;4976.477469865685;5009.373828271466;5030.622703297267;5074.898502029939;5006.924896126488;5020.246962955513;5023.673461225082;5029.1450453423;5075.0357432087785;4163.958360416357;4191.8742437726305;4201.34879093741;4205.474315710536;4251.87244382669;3607.0801945503727;3637.727170462226;3659.341318562631;3665.8533658653355;3711.31340701971;4504.572065653758;4506.369108324837;4506.954930450654;4507.954920450819;4554.863354099382;5205.167173252262;5218.921716174261;5221.7128057956415;5226.059309324282;5261.789528418897;4335.263005289045;4362.541933096979;4443.266860967969;4478.462584489882;4485.48416932057;4626.467956185014;4639.211334073216;4643.976780116078;4669.159551280734;4688.789004494738;4871.780769865364;4876.95123048765;4884.731339776346;4885.731284779229;4928.852134435972;5011.273365361929;5040.949590504048;5042.546170844642;5055.747212639427;5192.870178245574;4779.354787103696;4815.2054910939305;4815.590674792682;4816.34979277793;4817.927731083966;5007.47421520735;5010.649254552292;5013.573846223112;5023;5030.446650868328;5014.974925125351;5054.646174767731;5064.518870707277;5072.644914856072;5106.438291787975;4934.259861020875;4946.777395017305;4949.133901567283;4953.405591329064;4997.475265097112;4895.510448955076;4949.183384741621;4950.554450099508;4951.529604687549;4997.950020499894;4867.342908707279;4912.287718280901;4917.631177661761;4926.359573255597;4959.107360675113;5026.095302845523;5036.194208926554;5038.546530812097;5050.696958182521;5085.567726743268;4950;4952.133376658998;4953.72754498506;4957.206846904478;5004.249362595494 12:13:38 INFO - PROCESS | 13910 | |1;ext.html;528.1417696243603;552.6628756458559;554.1051202308273;556.9693666848324;560.990217608305;28787.856060719692;31426.371472570536;31467.68532314676;31473.05580832573;31499.21251968702;31082.378352432996;39162.60837391632;41214.175716485726;41444.17111657765;44949.32576011354;7648.770536883902;7788.1043679976965;7788.922110778904;7797.415193860455;7798.532088074734;7700.268474494914;7737.458377913548;7800.492967957093;7801.8049548761455;7805.1804560520895;7630.2369763023535;7790;9844.3601165924;10088.192944564455;10103.494825258707;9979.051990060969;10062.547185376603;10072.94963525185;10082.546285417175;10086.798264034715;9813.264005199639;9877.06167914047;9896.505174741234;9905.2571057171;9909.405435673883;36820;37021.07447313812;37143.25713485742;37151;37175.81412092949;3752.362098443244;3781.149241420686;3785.8296376662843;3813.199228162089;3821.789801560913;27614.861925690242;27982.44035119286;28066.298342541395;28081.297967550974;29352.412951740847;3441.741869359808;3554.9466757998653;3566.0906468850535;3592.0481072515804;3598.7300952428673;236291;237437;237491;237996;239271.80364098243;51694.22458663123;52042.47957520452;52065.21902171471;52065.7396713014;52065.958680826174;54862;54916.45083549193;54985.450145498835;55000;55014;12503.312317822607;12886.871131288755;12890;12891.871081289068;12913.354332283348;9695.224382049419;9740.610375584898;9752.171065459515;9759.853602195974;9770.804583908282;13949.860501394856;14315.785263220852;15187.494063479624;15767.448139315069;15856.682866342608;5714.628549144292;6090.786822461281;6205.317415084337;6211;6354.904676429857;807.4509333653173;817.448222449844;822.416084579943;832.887560179368;855.9015713193063;26741.19776406711;27649;27749.490835030567;28059.438811223663;28092.85953570219;920.3925409229876;939.6006697153682;955.1308309438377;963.3786207895868;964.8938616752151;12444.626661200178;13099;13155.144915580457;13189.538366157138;13306.26815525136;327679;331641;332109;332309;332579;41888;42034.57965420368;42147.15705685869;42169.578304217175;42177.57822421798;1203.0134500847907;1211.1824518450012;1230.975380492403;1232.5069972011268;1232.7596118756935;22262.888685556467;22264.55470890541;23163.536729265656;23521;23530.764692353543;35270.47094293536;35564;35640;35658.64341356553;35678.286434271686;280983;282790;282809;283225;283877;23000.654990175495;23368;23379.298621041395;38804.6119538801;39914.40128398018;39728.80135599303;41296.1740765189;42353;43162;44406.55593444024;36558.26883462263;36709.44935825912;37017.593856399246;37478.71718952101;37866;36991.63008369989;37095.814520927226;37170.8141459291;37202.44196337003;37411.62588374082;35402.11494712653;35689.64310356863;36400.27199456049;36627.084322892144;36877.63122368849;31432;33655;33743.8066525675;34213.486797698555;34249.82875085709;25720.742792572582;25732.485350292514;25829.612555812055;25892;26354.86822565875 12:13:38 INFO - PROCESS | 13910 | |2;jquery.html;341.63927262615334;362.95644522657284;372.09580718853175;373.9976862009813;401.0195073046401;12609.936950315247;15705.921470392646;16069.437569685064;17383.47849564512;17555.297788088494;16662.666746665058;17017.404390846325;17079;17148.742768858476;17166.485005449824;5006.474320196382;5012.874678133039;5149.227615857632;5188.844334669966;5196.220566914974;3863.150106976461;4046.433499310099;5119.744012799364;5131.897362052757;5166.948330516703;5761.971190144064;6019.7893073742425;6026.427489388523;6089.512838972893;6101.115338275948;5818.941810581882;5902.852428689274;5921.171036054956;6075.149479072911;6087.269527656666;5226.895462090775;5232.215167724834;5236.1098613235845;5250.212468129773;5254.6847189168775;20147.496312592157;20323.288684896106;20370.823981083842;20423.897880510503;20513.17947282107;2482.7889629381525;2538.1750930947514;2565.3330134165253;2607.908723194679;2618.56793629051;12254.632361029184;12929.159604625669;13045.152065115737;13421.530246441425;13588.184708917397;1705.155947805844;1757.3605681002164;1764.1179410294853;1803.7564928734716;1830.9816901830945;40501;52851.47148528542;58836;63507.68246158786;65355.01967470539;9049;9451.196648284973;9483.857742133874;9567.330286879851;9689.41863488193;12387.938060309642;12414.689632759253;12533.937330313473;12534.686632834251;12659.746805064031;6695.363940425652;6766.391024807693;6902.102726645504;7012.403945664573;7018.649067546704;6321.304656487782;6371.7657333981315;6532;6590.637514936724;6598.1422415085735;5985.970070149621;5999.340072592011;6031;6051.152838602599;6213.1922850029205;1676.4635316698539;1712.571857035741;1726.8532174765035;1738.3220543987854;1746.659401416737;957.1003256938455;965.4400447740294;967.2165545907696;980.2990861534038;1013.2400699475394;16332;16866;16897.83102168987;17165.914170429067;17201.483955481355;956.086936975187;970;970.2238209432426;971.3783178765455;971.3928794503436;9229.446233226028;9481.09929556691;9688.564014619358;9688.70933871971;9867.703968880945;324780;336445;338240;340039;340223;61129.694351527956;61779;62009;63380.27464919005;64324;927.7216834949623;930.4882314726873;953.9379940303858;954.608610469718;956.5073986896754;14425.134491930517;14557.34491947886;14785.704285914437;14945;15360.231988400312;31397.529037064007;31703.682963170075;31899.36201276008;32116.35767284688;33196.1700957478;40481.59518404778;40859;41018;41927.161456771304;42936;7341.6696248670005;7396.6671499783715;7442.665080071518;7568.962155189189;7761.525310191271;10673.786524269626;10823.458827058774;11033.282836615594;11146.386948717898;11916.702082447662;9231.76920576991;9461.668841590512;9498.572564234764;9823.312368134448;9960.854501732103;9724.124828765445;9957.402555846673;9999.550020249251;10076.798464030824;10174.389536627827;9516.23870090405;9528.904710952802;9787.21702263803;9788.510574471105;10210.69531435812;7336.669849856663;7545;7558.395328373607;7580.924190758022;7732.420068494998;9645.71062868115;9689.563969621526;9840.736630574866;9961.452120133463;10099 12:13:38 INFO - PROCESS | 13910 | |3;mootools.html;1311.4426368793268;1314.2246074815857;1318.1629665162623;1325.8674132586737;1336.9331533423326;6867.381935625793;7291.5989620570845;7322.853542929145;7324.194338622748;7337.33963943245;6406.455451286646;6624.569402988803;6927.653617319139;7125.786226413215;7132.072830532036;2145.367116700573;2173.89130543473;2178.2593918067855;2180.367693368929;2184.759676435591;2122.2147805312056;2221.389117992549;2229.2754854672216;2231.1856172497005;2233.6314508106143;1897.0419937931326;1963.342280336089;2099.307228614561;2169.4684802223396;2171.7285339332584;2119.205298013245;2120.300300900699;2133.9786602133936;2142.6678864775986;2143.2712877621607;2177.858439201447;2195.1438938813876;2217.4788924602703;2218.0129842220217;2220.5336879255374;7197.352238298578;7267.454940879402;7272.454565907578;7273.199948005715;7273.34539891407;4286.185624731291;4425.8229670813125;4427.424434823484;4429.601335879786;4431.379606855043;2841.176058942904;3029.7576193904656;3048.0551029181024;3048.2531779714113;3062.525308577174;1081.1891081688734;1144.9427528623535;1169.7952858249857;1176.8999635030948;1211.8485189351331;9136.314776391797;9191.31065170115;9253.02843201461;9258.907410925873;9284.628614855397;3966.7421617594764;3973.6821054315587;3999.4400783889696;4177.373393990865;4194.895127621834;7984;7984.600769961478;7986;7988.760337189894;7992.600369981477;4452.844150454784;4461.643068554507;4461.821527138943;4462.1075784842515;4464.241079016575;3975.7614543127506;4065.5324637666454;4074.490688663917;4074.694397920168;4079.8980025499004;3217.8551965161623;3237.109794806447;3310.8344582770765;3317.867285308609;3331.93336133276;1005.3264312910368;1014.6347314966606;1023.5905637744813;1029.2229366828146;1047.7328281288223;687.5290426058166;692.7990882643977;695.1727444341319;697.7034760226883;714.5462631229207;4594.885127871763;4740.644451666139;4772.546608072227;4862.610991120702;5033.345665063518;525.406290891298;654.0973456629915;821.7165078048064;913.6756451459636;918.0589895357313;3242.7567932405163;3317.3862835375535;3357.1439282982683;3492;3583.7312201584464;8351.24838764514;8586.957065214634;8597.269232115215;8679.956600216958;8791.252743516745;8455.069942306342;8524.445911015764;8563.186497282748;8609.526476043753;8612.267957223581;6611.305812889674;6636.668166591651;6763.763268285685;6789.898151527731;6824.112865327475;4794.832180873653;4796.95203047979;4853.0779151961015;4875.341828853059;4932.975335123301;7891;7893.447458677838;7902.407319450949;7911.485753426009;8039.959800200962;7885.842283154419;7996.800079998046;8062.677492900219;8106.229908158715;8166.387520936072;5888.263967004124;6020.217371741645;6022.488088512526;6023.969880150571;6026.758929642765;6966.303369662993;6986.056882320821;6992.895106573304;7000;7003.509754317149;6065.757369705162;6299.08663243818;6301.779437719658;6366.4588509977175;6398.072279519351;6186.752529898754;6197.4732147767945;6212.81361559154;6218.782342617987;6237.688115594293;5751.7411716472025;5850.619709718854;5861.648301101947;5863.20846685692;5878.118282257525;4253.532111467798;4283.914321713611;4309.719868208556;4333.523312435693;4424.867253982386;4256.700083875943;4262.531121576562;4273.91452170961;4300.118475712534;4448.266036104006 12:13:38 INFO - PROCESS | 13910 | |4;prototype.html;336.57423359450297;350.75972958523414;352.1478023183895;354.1659392131532;362.0026826094112;4699.553542413474;4799.208130658437;4821.156297647911;4901.33831932689;4945.615227736231;4611.331356953248;4693.554112359329;4697.6241900647865;4753.857384278468;4806;3831.578526362098;3837.4819399381145;3849.7305188636806;3857.842647205848;3859.8456061757497;3662.597114317423;3787.602301758317;3862.7832232846563;3869.1487872667967;3871.8064096795188;3755.6619904208615;3789.5263092113487;3824.1778017726183;3826.9043273918237;3828.5022947016987;3830.674392676598;3834.8082595870096;3840.4047372657005;3848.692104631623;3849.942250866239;3432.1591210153424;3452.637473065317;3458.4466485362473;3463.428534291864;3465.1163953192026;4620.884477888079;4650.372199753057;4660.627149828039;4701.106789709944;4740.691855029412;4347.847825326098;4361.978190109061;4380.956190438087;4408.713433626804;4409.536998615164;5014.821516943515;5040.621953353513;5060.5951523878;5072.898542029176;5088.516590923857;3703.925921481555;3754.6808521275725;3762.6801721853676;3768.4347347898033;3785.7885476647484;5246.2655228267695;5247.921281180786;5253.347059734848;5253.737313134367;5276.4459731728;3847.5382954045695;3853.190829925747;3854.094287842355;3854.8843534694006;3859.7877116758846;3859.421086836941;3883.805809709503;3917.6474117329576;3919.2945269851703;3931.9213615727526;4232.597903199191;4340.739555626673;4345.9130817383475;4368.759718215465;4391.736495810198;4988.825391111293;5000.324956130876;5003.874903127378;5009.24861270805;5049.166887463527;1276.3745764575476;1287.2340957130493;1298.9025823063307;1333.8132661427408;1342.7985802129563;933.3699752666922;960.4573416020066;961.6826447272383;964.5611246882652;966.6713317472094;4271.017665936852;4285.292926667127;4291.506476755213;4307.612314891675;4332.068605249887;4638.24040131202;4905.509449055137;4932.334134891744;4962.568341512633;4982.928670335895;4254.681198746691;4301.720388174758;4317;4320.222359975234;4335.263005289108;2662.9481354865106;2669.172556507489;2674.852883091449;2679.834272091646;2682.3964607963444;4609.907801843944;4756.453007904066;4909.852704418873;4926.778294976663;4935.555799978019;5280.877548777679;5301.6818990860675;5301.920471192935;5329.147336426153;5342.492463215988;4892.902141957141;4895.4370247422;4935.580475659537;4948.331975183376;4958.801647934042;5063.443021267584;5181.274621553061;5212.50481204285;5220.451852555427;5225.0144944920685;5079.72061536619;5083.46623604531;5095.337606111182;5098.923516147335;5111.131107711623;4762.309465127468;4906.282801019614;4917;4933.235348520985;4954.702717836942;3576.7496275260482;3649.2883887641656;3651.3245049665898;3654.25087856994;3671.5043469131324;5149.227615857501;5160.870978225575;5171.095058364695;5185.092608793521;5185.729496273287;5040.395152581567;5051.823186188466;5104.540591346797;5106.7446627669215;5113.335266415343;4818.277258411266;5024.472430394756;5048.192289233705;5065.594752419724;5075.340205773374;4997.875478017562;5005.549500544968;5013.623978201576;5024.296598476143;5025.723585202849;4853.52470240173;5042.092423363683;5100.69395836251;5109.284700142058;5113.821016264413;4949.678270912379;4956.281339205867;4965.4289756678445;4970;4972.712552226017 12:13:38 INFO - PROCESS | 13910 | |5;yui.html;665.2017578905314;686.9175698916131;687.5015613680081;698.7833771530823;709.9254578269282;12599.622011339663;13560.932195339034;13589.592312230625;13592.932035339822;13598.796018059724;12101.394930253475;12102.334371609559;12126.151169418144;12153.149279550435;12205.389730513483;673.2358772792891;691.2223748283182;802.6187560908551;809.1988930958338;819.9344052475788;819.5082950229844;820.8071103290722;821.7165078048064;836.2640876029117;836.6277006732007;868.4311775786838;870.2863651805523;871.1071152069148;872.3675335381861;876.2989608313355;706.3113464372248;728.6429649471773;834.7454026521908;873.6068769053887;873.7990262239689;629.7984644913629;630.1335663462738;631.0282165465178;631.1290419221446;632.6994677528184;13229.801552976713;13296;13296.734065318737;13302.401391937387;13313.53402630913;13082.607521774362;13110.803337949937;13111.672208194776;13154.473821047146;13197.538086166938;11959.222650527689;12049.216800907912;12089.214201076902;12566.434510446961;12647.747045059139;4139.551157095023;4218.78906054696;4239.51245606753;4245.915081698379;4379.102284031797;62365;63354.36645633577;63882.680586597235;64670.70658586849;65194;10311.381317120997;10334.431606261578;10376.221783366278;10578.52396642153;10618.734531636617;19899.502512437306;19914.203431862563;20104.89947550282;20105.497362566053;20106.899465502873;6426.518011149183;6464.644444555594;6475.7733479328;6479.157709497735;6589.406953374123;3679.5952445231005;3686.705063594906;3696.8336424860945;3739.682127019234;3740.663340299386;2716.2258756254373;2771.1270949650793;2774.542200536929;2779.3190668285997;2790.7209279072335;472.88887111528766;483.97338146402285;487.12317827909624;491.1723745488863;495.35109007199964;529.8331025726884;560.3863769172749;566.164906762522;572.4418691775569;593.4777395891588;7376.7786966391095;7570.735024274234;7596.316331530189;7796.4932279401655;7902.3678105751405;533.0884188038498;548.2653244652186;569.663898300005;582.8601135727395;585.8916100521417;3910.2179564087296;3970.50368703912;3974.82113304902;4077.571854955246;4094.119764250641;62919.37080629227;64504.35495645077;65436.34563654398;66669;67142;54617.453825462035;54691.72654136704;55387;55905.7204713982;56769.71615141898;40588.59411405907;43266.7836660821;43330.35004474936;45821;47562.286565702234;8189.50862948225;8526.232639062266;8526.829463410573;8528.403011789114;8579.82840343202;31714.524282136248;33075.173120672174;33121.18469930083;33917.8128765492;34111;38807;39755.40366894557;39998.80000599979;42495.362569560864;46672.53327466682;9425.19885809714;9574.234061275221;9597.952010239904;11136.610218642632;12048.879511204776;10974.683355758858;11009.229353945148;11385.886141138813;11411.486483108114;12351.629451116482;12393;12915.870841291468;12966.286854223108;12994.415251313525;13264.137831040951;11906.702332441759;12090.818637720266;12192.634220973385;13063;13581.049326547047;11643.243189192675;11724.70688232801;12789;12809.487620495076;12823.551175708806;10081.747956301151;10245.487725613839;10246.743831404274;10436.895631043591;10546.57813687444;9447.958768210025;10012.599496020077;10504.369737815756;10535.57857685684;10637 12:13:38 INFO - PROCESS | 13910 | __end_tp_report 12:13:38 INFO - PROCESS | 13910 | __start_cc_report 12:13:38 INFO - PROCESS | 13910 | _x_x_mozilla_cycle_collect,1055 12:13:38 INFO - PROCESS | 13910 | __end_cc_report 12:13:38 INFO - PROCESS | 13910 | __startTimestamp1483301618490__endTimestamp 12:13:38 INFO - PROCESS | 13910 | 12:13:38 INFO - PROCESS | 13910 | ------- Summary: start ------- 12:13:38 INFO - PROCESS | 13910 | Number of tests: 6 12:13:38 INFO - PROCESS | 13910 | 12:13:38 INFO - PROCESS | 13910 | [#0] dojo.html Cycles:165 Average:4707.61 Median:4871.78 stddev:504.28 (10.4%) stddev-sans-first:476.74 12:13:38 INFO - PROCESS | 13910 | Values: 2555.7 2569.2 2583.8 2589.5 2605.9 4724.1 4755.7 4771.3 4796.2 4805.9 4760.0 4772.2 4797.5 4815.1 4820.2 4361.1 4384.8 4399.8 4424.4 4510.2 4393.9 4409.7 4426.6 4431.3 4474.2 4356.0 4367.3 4434.2 4463.0 4484.4 4403.3 4416.5 4424.4 4426.0 4430.1 4397.5 4400.0 4408.6 4411.5 4413.1 4711.0 4721.3 4777.6 4795.7 4798.0 5104.7 5105.6 5112.4 5158.2 5161.4 5135.9 5143.3 5160.7 5166.8 5185.7 4611.5 4613.2 4646.0 4731.2 4786.4 5092.6 5112.8 5119.5 5125.4 5166.3 4850.6 4873.8 4882.4 4897.5 4936.7 4966.6 4982.1 4982.9 4986.9 4993.5 4959.2 4976.5 5009.4 5030.6 5074.9 5006.9 5020.2 5023.7 5029.1 5075.0 4164.0 4191.9 4201.3 4205.5 4251.9 3607.1 3637.7 3659.3 3665.9 3711.3 4504.6 4506.4 4507.0 4508.0 4554.9 5205.2 5218.9 5221.7 5226.1 5261.8 4335.3 4362.5 4443.3 4478.5 4485.5 4626.5 4639.2 4644.0 4669.2 4688.8 4871.8 4877.0 4884.7 4885.7 4928.9 5011.3 5040.9 5042.5 5055.7 5192.9 4779.4 4815.2 4815.6 4816.3 4817.9 5007.5 5010.6 5013.6 5023.0 5030.4 5015.0 5054.6 5064.5 5072.6 5106.4 4934.3 4946.8 4949.1 4953.4 4997.5 4895.5 4949.2 4950.6 4951.5 4998.0 4867.3 4912.3 4917.6 4926.4 4959.1 5026.1 5036.2 5038.5 5050.7 5085.6 4950.0 4952.1 4953.7 4957.2 5004.2 12:13:38 INFO - PROCESS | 13910 | 12:13:38 INFO - PROCESS | 13910 | [#1] ext.html Cycles:180 Average:43794.59 Median:36764.72 stddev:75044.92 (204.1%) stddev-sans-first:75185.12 12:13:38 INFO - PROCESS | 13910 | Values: 528.1 552.7 554.1 557.0 561.0 28787.9 31426.4 31467.7 31473.1 31499.2 31082.4 39162.6 41214.2 41444.2 44949.3 7648.8 7788.1 7788.9 7797.4 7798.5 7700.3 7737.5 7800.5 7801.8 7805.2 7630.2 7790.0 9844.4 10088.2 10103.5 9979.1 10062.5 10072.9 10082.5 10086.8 9813.3 9877.1 9896.5 9905.3 9909.4 36820.0 37021.1 37143.3 37151.0 37175.8 3752.4 3781.1 3785.8 3813.2 3821.8 27614.9 27982.4 28066.3 28081.3 29352.4 3441.7 3554.9 3566.1 3592.0 3598.7 236291.0 237437.0 237491.0 237996.0 239271.8 51694.2 52042.5 52065.2 52065.7 52066.0 54862.0 54916.5 54985.5 55000.0 55014.0 12503.3 12886.9 12890.0 12891.9 12913.4 9695.2 9740.6 9752.2 9759.9 9770.8 13949.9 14315.8 15187.5 15767.4 15856.7 5714.6 6090.8 6205.3 6211.0 6354.9 807.5 817.4 822.4 832.9 855.9 26741.2 27649.0 27749.5 28059.4 28092.9 920.4 939.6 955.1 963.4 964.9 12444.6 13099.0 13155.1 13189.5 13306.3 327679.0 331641.0 332109.0 332309.0 332579.0 41888.0 42034.6 42147.2 42169.6 42177.6 1203.0 1211.2 1231.0 1232.5 1232.8 22262.9 22264.6 23163.5 23521.0 23530.8 35270.5 35564.0 35640.0 35658.6 35678.3 280983.0 282790.0 282809.0 283225.0 283877.0 23000.7 23368.0 23379.3 38804.6 39914.4 39728.8 41296.2 42353.0 43162.0 44406.6 36558.3 36709.4 37017.6 37478.7 37866.0 36991.6 37095.8 37170.8 37202.4 37411.6 35402.1 35689.6 36400.3 36627.1 36877.6 31432.0 33655.0 33743.8 34213.5 34249.8 25720.7 25732.5 25829.6 25892.0 26354.9 12:13:38 INFO - PROCESS | 13910 | 12:13:38 INFO - PROCESS | 13910 | [#2] jquery.html Cycles:180 Average:21633.32 Median:5211.56 stddev:55134.21 (1057.9%) stddev-sans-first:55265.70 12:13:38 INFO - PROCESS | 13910 | Values: 341.6 363.0 372.1 374.0 401.0 12609.9 15705.9 16069.4 17383.5 17555.3 16662.7 17017.4 17079.0 17148.7 17166.5 5006.5 5012.9 5149.2 5188.8 5196.2 3863.2 4046.4 5119.7 5131.9 5166.9 5762.0 6019.8 6026.4 6089.5 6101.1 5818.9 5902.9 5921.2 6075.1 6087.3 5226.9 5232.2 5236.1 5250.2 5254.7 20147.5 20323.3 20370.8 20423.9 20513.2 2482.8 2538.2 2565.3 2607.9 2618.6 12254.6 12929.2 13045.2 13421.5 13588.2 1705.2 1757.4 1764.1 1803.8 1831.0 40501.0 52851.5 58836.0 63507.7 65355.0 9049.0 9451.2 9483.9 9567.3 9689.4 12387.9 12414.7 12533.9 12534.7 12659.7 6695.4 6766.4 6902.1 7012.4 7018.6 6321.3 6371.8 6532.0 6590.6 6598.1 5986.0 5999.3 6031.0 6051.2 6213.2 1676.5 1712.6 1726.9 1738.3 1746.7 957.1 965.4 967.2 980.3 1013.2 16332.0 16866.0 16897.8 17165.9 17201.5 956.1 970.0 970.2 971.4 971.4 9229.4 9481.1 9688.6 9688.7 9867.7 324780.0 336445.0 338240.0 340039.0 340223.0 61129.7 61779.0 62009.0 63380.3 64324.0 927.7 930.5 953.9 954.6 956.5 14425.1 14557.3 14785.7 14945.0 15360.2 31397.5 31703.7 31899.4 32116.4 33196.2 40481.6 40859.0 41018.0 41927.2 42936.0 7341.7 7396.7 7442.7 7569.0 7761.5 10673.8 10823.5 11033.3 11146.4 11916.7 9231.8 9461.7 9498.6 9823.3 9960.9 9724.1 9957.4 9999.6 10076.8 10174.4 9516.2 9528.9 9787.2 9788.5 10210.7 7336.7 7545.0 7558.4 7580.9 7732.4 9645.7 9689.6 9840.7 9961.5 10099.0 12:13:38 INFO - PROCESS | 13910 | 12:13:38 INFO - PROCESS | 13910 | [#3] mootools.html Cycles:180 Average:4733.49 Median:4857.84 stddev:2518.51 (51.8%) stddev-sans-first:2512.44 12:13:38 INFO - PROCESS | 13910 | Values: 1311.4 1314.2 1318.2 1325.9 1336.9 6867.4 7291.6 7322.9 7324.2 7337.3 6406.5 6624.6 6927.7 7125.8 7132.1 2145.4 2173.9 2178.3 2180.4 2184.8 2122.2 2221.4 2229.3 2231.2 2233.6 1897.0 1963.3 2099.3 2169.5 2171.7 2119.2 2120.3 2134.0 2142.7 2143.3 2177.9 2195.1 2217.5 2218.0 2220.5 7197.4 7267.5 7272.5 7273.2 7273.3 4286.2 4425.8 4427.4 4429.6 4431.4 2841.2 3029.8 3048.1 3048.3 3062.5 1081.2 1144.9 1169.8 1176.9 1211.8 9136.3 9191.3 9253.0 9258.9 9284.6 3966.7 3973.7 3999.4 4177.4 4194.9 7984.0 7984.6 7986.0 7988.8 7992.6 4452.8 4461.6 4461.8 4462.1 4464.2 3975.8 4065.5 4074.5 4074.7 4079.9 3217.9 3237.1 3310.8 3317.9 3331.9 1005.3 1014.6 1023.6 1029.2 1047.7 687.5 692.8 695.2 697.7 714.5 4594.9 4740.6 4772.5 4862.6 5033.3 525.4 654.1 821.7 913.7 918.1 3242.8 3317.4 3357.1 3492.0 3583.7 8351.2 8587.0 8597.3 8680.0 8791.3 8455.1 8524.4 8563.2 8609.5 8612.3 6611.3 6636.7 6763.8 6789.9 6824.1 4794.8 4797.0 4853.1 4875.3 4933.0 7891.0 7893.4 7902.4 7911.5 8040.0 7885.8 7996.8 8062.7 8106.2 8166.4 5888.3 6020.2 6022.5 6024.0 6026.8 6966.3 6986.1 6992.9 7000.0 7003.5 6065.8 6299.1 6301.8 6366.5 6398.1 6186.8 6197.5 6212.8 6218.8 6237.7 5751.7 5850.6 5861.6 5863.2 5878.1 4253.5 4283.9 4309.7 4333.5 4424.9 4256.7 4262.5 4273.9 4300.1 4448.3 12:13:38 INFO - PROCESS | 13910 | 12:13:38 INFO - PROCESS | 13910 | [#4] prototype.html Cycles:180 Average:4207.28 Median:4747.27 stddev:1188.96 (25.0%) stddev-sans-first:1156.26 12:13:38 INFO - PROCESS | 13910 | Values: 336.6 350.8 352.1 354.2 362.0 4699.6 4799.2 4821.2 4901.3 4945.6 4611.3 4693.6 4697.6 4753.9 4806.0 3831.6 3837.5 3849.7 3857.8 3859.8 3662.6 3787.6 3862.8 3869.1 3871.8 3755.7 3789.5 3824.2 3826.9 3828.5 3830.7 3834.8 3840.4 3848.7 3849.9 3432.2 3452.6 3458.4 3463.4 3465.1 4620.9 4650.4 4660.6 4701.1 4740.7 4347.8 4362.0 4381.0 4408.7 4409.5 5014.8 5040.6 5060.6 5072.9 5088.5 3703.9 3754.7 3762.7 3768.4 3785.8 5246.3 5247.9 5253.3 5253.7 5276.4 3847.5 3853.2 3854.1 3854.9 3859.8 3859.4 3883.8 3917.6 3919.3 3931.9 4232.6 4340.7 4345.9 4368.8 4391.7 4988.8 5000.3 5003.9 5009.2 5049.2 1276.4 1287.2 1298.9 1333.8 1342.8 933.4 960.5 961.7 964.6 966.7 4271.0 4285.3 4291.5 4307.6 4332.1 4638.2 4905.5 4932.3 4962.6 4982.9 4254.7 4301.7 4317.0 4320.2 4335.3 2662.9 2669.2 2674.9 2679.8 2682.4 4609.9 4756.5 4909.9 4926.8 4935.6 5280.9 5301.7 5301.9 5329.1 5342.5 4892.9 4895.4 4935.6 4948.3 4958.8 5063.4 5181.3 5212.5 5220.5 5225.0 5079.7 5083.5 5095.3 5098.9 5111.1 4762.3 4906.3 4917.0 4933.2 4954.7 3576.7 3649.3 3651.3 3654.3 3671.5 5149.2 5160.9 5171.1 5185.1 5185.7 5040.4 5051.8 5104.5 5106.7 5113.3 4818.3 5024.5 5048.2 5065.6 5075.3 4997.9 5005.5 5013.6 5024.3 5025.7 4853.5 5042.1 5100.7 5109.3 5113.8 4949.7 4956.3 4965.4 4970.0 4972.7 12:13:38 INFO - PROCESS | 13910 | 12:13:38 INFO - PROCESS | 13910 | [#5] yui.html Cycles:180 Average:14497.45 Median:4179.17 stddev:17648.73 (422.3%) stddev-sans-first:17667.68 12:13:38 INFO - PROCESS | 13910 | Values: 665.2 686.9 687.5 698.8 709.9 12599.6 13560.9 13589.6 13592.9 13598.8 12101.4 12102.3 12126.2 12153.1 12205.4 673.2 691.2 802.6 809.2 819.9 819.5 820.8 821.7 836.3 836.6 868.4 870.3 871.1 872.4 876.3 706.3 728.6 834.7 873.6 873.8 629.8 630.1 631.0 631.1 632.7 13229.8 13296.0 13296.7 13302.4 13313.5 13082.6 13110.8 13111.7 13154.5 13197.5 11959.2 12049.2 12089.2 12566.4 12647.7 4139.6 4218.8 4239.5 4245.9 4379.1 62365.0 63354.4 63882.7 64670.7 65194.0 10311.4 10334.4 10376.2 10578.5 10618.7 19899.5 19914.2 20104.9 20105.5 20106.9 6426.5 6464.6 6475.8 6479.2 6589.4 3679.6 3686.7 3696.8 3739.7 3740.7 2716.2 2771.1 2774.5 2779.3 2790.7 472.9 484.0 487.1 491.2 495.4 529.8 560.4 566.2 572.4 593.5 7376.8 7570.7 7596.3 7796.5 7902.4 533.1 548.3 569.7 582.9 585.9 3910.2 3970.5 3974.8 4077.6 4094.1 62919.4 64504.4 65436.3 66669.0 67142.0 54617.5 54691.7 55387.0 55905.7 56769.7 40588.6 43266.8 43330.4 45821.0 47562.3 8189.5 8526.2 8526.8 8528.4 8579.8 31714.5 33075.2 33121.2 33917.8 34111.0 38807.0 39755.4 39998.8 42495.4 46672.5 9425.2 9574.2 9598.0 11136.6 12048.9 10974.7 11009.2 11385.9 11411.5 12351.6 12393.0 12915.9 12966.3 12994.4 13264.1 11906.7 12090.8 12192.6 13063.0 13581.0 11643.2 11724.7 12789.0 12809.5 12823.6 10081.7 10245.5 10246.7 10436.9 10546.6 9448.0 10012.6 10504.4 10535.6 10637.0 12:13:38 INFO - PROCESS | 13910 | -------- Summary: end -------- 12:13:38 INFO - PROCESS | 13910 | 12:13:39 INFO - TEST-INFO | 13910: exit 0 12:13:39 INFO - TEST-OK | dromaeo_css | took 1121222ms 12:13:39 INFO - TEST-START | kraken 12:13:39 INFO - operating with platform_type : linux_ 12:13:39 INFO - Initialising browser for kraken test... 12:13:39 INFO - TEST-INFO | started process 14209 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_V5cEI/profile http://localhost:48615/getInfo.html) 12:13:39 INFO - PROCESS | 14209 | 1483301619871 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 12:13:43 INFO - PROCESS | 14209 | __metrics Screen width/height:1600/1200 12:13:43 INFO - PROCESS | 14209 | colorDepth:24 12:13:43 INFO - PROCESS | 14209 | Browser inner width/height: 1024/697 12:13:43 INFO - PROCESS | 14209 | __metrics 12:13:44 INFO - PROCESS | 14209 | 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" 12:13:44 INFO - PROCESS | 14209 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:13:44 INFO - PROCESS | 14209 | console.error: PushService: 12:13:44 INFO - PROCESS | 14209 | stateChangeProcessEnqueue: Error transitioning state 12:13:44 INFO - PROCESS | 14209 | UnknownError 12:13:45 INFO - TEST-INFO | 14209: exit 0 12:13:45 INFO - Browser initialized. 12:13:45 INFO - Running cycle 1/1 for kraken test... 12:13:45 INFO - TEST-INFO | started process 14294 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_V5cEI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:13:57 INFO - PROCESS | 14294 | RSS: Main: 151216128 12:13:57 INFO - PROCESS | 14294 | 12:14:00 INFO - PROCESS | 14294 | Cycle 1(1): loaded http://localhost:48615/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:48615/tests/kraken/driver.html?testName=audio-beat-detection) 12:14:01 INFO - PROCESS | 14294 | RSS: Main: 205312000 12:14:01 INFO - PROCESS | 14294 | 12:14:03 INFO - PROCESS | 14294 | Cycle 1(1): loaded http://localhost:48615/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:48615/tests/kraken/driver.html?testName=audio-dft) 12:14:04 INFO - PROCESS | 14294 | RSS: Main: 185253888 12:14:04 INFO - PROCESS | 14294 | 12:14:07 INFO - PROCESS | 14294 | Cycle 1(1): loaded http://localhost:48615/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:48615/tests/kraken/driver.html?testName=audio-fft) 12:14:08 INFO - PROCESS | 14294 | RSS: Main: 268423168 12:14:08 INFO - PROCESS | 14294 | 12:14:10 INFO - PROCESS | 14294 | Cycle 1(1): loaded http://localhost:48615/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:48615/tests/kraken/driver.html?testName=audio-oscillator) 12:14:11 INFO - PROCESS | 14294 | RSS: Main: 179060736 12:14:11 INFO - PROCESS | 14294 | 12:14:13 INFO - PROCESS | 14294 | Cycle 1(1): loaded http://localhost:48615/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:48615/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:14:13 INFO - PROCESS | 14294 | RSS: Main: 181878784 12:14:13 INFO - PROCESS | 14294 | 12:14:16 INFO - PROCESS | 14294 | Cycle 1(1): loaded http://localhost:48615/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:48615/tests/kraken/driver.html?testName=imaging-darkroom) 12:14:17 INFO - PROCESS | 14294 | RSS: Main: 221319168 12:14:17 INFO - PROCESS | 14294 | 12:14:21 INFO - PROCESS | 14294 | Cycle 1(1): loaded http://localhost:48615/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:48615/tests/kraken/driver.html?testName=imaging-desaturate) 12:14:21 INFO - PROCESS | 14294 | RSS: Main: 221040640 12:14:21 INFO - PROCESS | 14294 | 12:14:24 INFO - PROCESS | 14294 | Cycle 1(1): loaded http://localhost:48615/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:48615/tests/kraken/driver.html?testName=json-parse-financial) 12:14:25 INFO - PROCESS | 14294 | RSS: Main: 219181056 12:14:25 INFO - PROCESS | 14294 | 12:14:26 INFO - PROCESS | 14294 | Cycle 1(1): loaded http://localhost:48615/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:48615/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:14:27 INFO - PROCESS | 14294 | RSS: Main: 182149120 12:14:27 INFO - PROCESS | 14294 | 12:14:29 INFO - PROCESS | 14294 | Cycle 1(1): loaded http://localhost:48615/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:48615/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:14:29 INFO - PROCESS | 14294 | RSS: Main: 190246912 12:14:29 INFO - PROCESS | 14294 | 12:14:32 INFO - PROCESS | 14294 | Cycle 1(1): loaded http://localhost:48615/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:48615/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:14:32 INFO - PROCESS | 14294 | RSS: Main: 197865472 12:14:32 INFO - PROCESS | 14294 | 12:14:35 INFO - PROCESS | 14294 | Cycle 1(1): loaded http://localhost:48615/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:48615/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:14:36 INFO - PROCESS | 14294 | RSS: Main: 201515008 12:14:36 INFO - PROCESS | 14294 | 12:14:39 INFO - PROCESS | 14294 | Cycle 1(1): loaded http://localhost:48615/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:48615/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:14:39 INFO - PROCESS | 14294 | RSS: Main: 202797056 12:14:39 INFO - PROCESS | 14294 | 12:14:41 INFO - PROCESS | 14294 | Cycle 1(1): loaded http://localhost:48615/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:48615/tests/kraken/driver.html?testName=ai-astar) 12:14:42 INFO - PROCESS | 14294 | RSS: Main: 255602688 12:14:42 INFO - PROCESS | 14294 | 12:14:42 INFO - PROCESS | 14294 | __start_tp_report 12:14:42 INFO - PROCESS | 14294 | _x_x_mozilla_page_load 12:14:42 INFO - PROCESS | 14294 | _x_x_mozilla_page_load_details 12:14:42 INFO - PROCESS | 14294 | |i|pagename|runs| 12:14:42 INFO - PROCESS | 14294 | |0;ai-astar;105;114;115;114;116;114;115;115;116;114 12:14:42 INFO - PROCESS | 14294 | |1;audio-beat-detection;159;129;127;164;133;128;127;127;158;127 12:14:42 INFO - PROCESS | 14294 | |2;audio-dft;161;161;164;162;164;162;162;160;161;162 12:14:42 INFO - PROCESS | 14294 | |3;audio-fft;77;76;75;109;77;77;77;77;92;75 12:14:42 INFO - PROCESS | 14294 | |4;audio-oscillator;85;85;111;86;85;85;97;85;84;109 12:14:42 INFO - PROCESS | 14294 | |5;imaging-gaussian-blur;117;117;117;117;116;117;117;117;117;117 12:14:42 INFO - PROCESS | 14294 | |6;imaging-darkroom;149;150;149;149;149;150;150;150;149;149 12:14:42 INFO - PROCESS | 14294 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;95;94 12:14:42 INFO - PROCESS | 14294 | |8;json-parse-financial;62;60;61;60;61;61;62;61;62;61 12:14:42 INFO - PROCESS | 14294 | |9;json-stringify-tinderbox;67;68;67;67;67;66;66;67;68;68 12:14:42 INFO - PROCESS | 14294 | |10;stanford-crypto-aes;87;85;85;95;89;92;85;88;85;84 12:14:42 INFO - PROCESS | 14294 | |11;stanford-crypto-ccm;142;139;142;134;132;132;141;134;223;132 12:14:42 INFO - PROCESS | 14294 | |12;stanford-crypto-pbkdf2;179;179;184;177;179;176;179;179;182;177 12:14:42 INFO - PROCESS | 14294 | |13;stanford-crypto-sha256-iterative;69;69;70;69;76;69;70;70;75;68 12:14:42 INFO - PROCESS | 14294 | __end_tp_report 12:14:42 INFO - PROCESS | 14294 | __start_cc_report 12:14:42 INFO - PROCESS | 14294 | _x_x_mozilla_cycle_collect,1861 12:14:42 INFO - PROCESS | 14294 | __end_cc_report 12:14:42 INFO - PROCESS | 14294 | __startTimestamp1483301682032__endTimestamp 12:14:42 INFO - PROCESS | 14294 | 12:14:42 INFO - PROCESS | 14294 | ------- Summary: start ------- 12:14:42 INFO - PROCESS | 14294 | Number of tests: 14 12:14:42 INFO - PROCESS | 14294 | 12:14:42 INFO - PROCESS | 14294 | [#0] ai-astar Cycles:10 Average:113.80 Median:115.00 stddev:3.19 (2.8%) stddev-sans-first:0.83 12:14:42 INFO - PROCESS | 14294 | Values: 105.0 114.0 115.0 114.0 116.0 114.0 115.0 115.0 116.0 114.0 12:14:42 INFO - PROCESS | 14294 | 12:14:42 INFO - PROCESS | 14294 | [#1] audio-beat-detection Cycles:10 Average:137.90 Median:131.00 stddev:15.66 (12.0%) stddev-sans-first:14.63 12:14:42 INFO - PROCESS | 14294 | Values: 159.0 129.0 127.0 164.0 133.0 128.0 127.0 127.0 158.0 127.0 12:14:42 INFO - PROCESS | 14294 | 12:14:42 INFO - PROCESS | 14294 | [#2] audio-dft Cycles:10 Average:161.90 Median:162.00 stddev:1.29 (0.8%) stddev-sans-first:1.32 12:14:42 INFO - PROCESS | 14294 | Values: 161.0 161.0 164.0 162.0 164.0 162.0 162.0 160.0 161.0 162.0 12:14:42 INFO - PROCESS | 14294 | 12:14:42 INFO - PROCESS | 14294 | [#3] audio-fft Cycles:10 Average:81.20 Median:77.00 stddev:10.96 (14.2%) stddev-sans-first:11.52 12:14:42 INFO - PROCESS | 14294 | Values: 77.0 76.0 75.0 109.0 77.0 77.0 77.0 77.0 92.0 75.0 12:14:42 INFO - PROCESS | 14294 | 12:14:42 INFO - PROCESS | 14294 | [#4] audio-oscillator Cycles:10 Average:91.20 Median:85.00 stddev:10.61 (12.5%) stddev-sans-first:11.02 12:14:42 INFO - PROCESS | 14294 | Values: 85.0 85.0 111.0 86.0 85.0 85.0 97.0 85.0 84.0 109.0 12:14:42 INFO - PROCESS | 14294 | 12:14:42 INFO - PROCESS | 14294 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 12:14:42 INFO - PROCESS | 14294 | Values: 117.0 117.0 117.0 117.0 116.0 117.0 117.0 117.0 117.0 117.0 12:14:42 INFO - PROCESS | 14294 | 12:14:42 INFO - PROCESS | 14294 | [#6] imaging-darkroom Cycles:10 Average:149.40 Median:149.50 stddev:0.52 (0.3%) stddev-sans-first:0.53 12:14:42 INFO - PROCESS | 14294 | Values: 149.0 150.0 149.0 149.0 149.0 150.0 150.0 150.0 149.0 149.0 12:14:42 INFO - PROCESS | 14294 | 12:14:42 INFO - PROCESS | 14294 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 12:14:42 INFO - PROCESS | 14294 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 94.0 12:14:42 INFO - PROCESS | 14294 | 12:14:42 INFO - PROCESS | 14294 | [#8] json-parse-financial Cycles:10 Average:61.10 Median:61.00 stddev:0.74 (1.2%) stddev-sans-first:0.71 12:14:42 INFO - PROCESS | 14294 | Values: 62.0 60.0 61.0 60.0 61.0 61.0 62.0 61.0 62.0 61.0 12:14:42 INFO - PROCESS | 14294 | 12:14:42 INFO - PROCESS | 14294 | [#9] json-stringify-tinderbox Cycles:10 Average:67.10 Median:67.00 stddev:0.74 (1.1%) stddev-sans-first:0.78 12:14:42 INFO - PROCESS | 14294 | Values: 67.0 68.0 67.0 67.0 67.0 66.0 66.0 67.0 68.0 68.0 12:14:42 INFO - PROCESS | 14294 | 12:14:42 INFO - PROCESS | 14294 | [#10] stanford-crypto-aes Cycles:10 Average:87.50 Median:87.50 stddev:3.60 (4.1%) stddev-sans-first:3.81 12:14:42 INFO - PROCESS | 14294 | Values: 87.0 85.0 85.0 95.0 89.0 92.0 85.0 88.0 85.0 84.0 12:14:42 INFO - PROCESS | 14294 | 12:14:42 INFO - PROCESS | 14294 | [#11] stanford-crypto-ccm Cycles:10 Average:145.10 Median:140.00 stddev:27.69 (19.8%) stddev-sans-first:29.35 12:14:42 INFO - PROCESS | 14294 | Values: 142.0 139.0 142.0 134.0 132.0 132.0 141.0 134.0 223.0 132.0 12:14:42 INFO - PROCESS | 14294 | 12:14:42 INFO - PROCESS | 14294 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.10 Median:179.00 stddev:2.38 (1.3%) stddev-sans-first:2.52 12:14:42 INFO - PROCESS | 14294 | Values: 179.0 179.0 184.0 177.0 179.0 176.0 179.0 179.0 182.0 177.0 12:14:42 INFO - PROCESS | 14294 | 12:14:42 INFO - PROCESS | 14294 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.50 Median:70.00 stddev:2.72 (3.9%) stddev-sans-first:2.83 12:14:42 INFO - PROCESS | 14294 | Values: 69.0 69.0 70.0 69.0 76.0 69.0 70.0 70.0 75.0 68.0 12:14:42 INFO - PROCESS | 14294 | -------- Summary: end -------- 12:14:42 INFO - PROCESS | 14294 | 12:14:43 INFO - TEST-INFO | 14294: exit 0 12:14:43 INFO - TEST-OK | kraken | took 63777ms 12:14:43 INFO - SUITE-END | took 1184s 12:14:43 INFO - Completed test suite (00:19:45) 12:14:43 INFO - javascript benchmark 12:14:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2555.718870924197, 2569.177863083813, 2583.8062145339104, 2589.5079934812375, 2605.92182234533, 4724.1260366832075, 4755.667103302771, 4771.26045462953, 4796.232602783555, 4805.903881922364, 4759.952400475994, 4772.164871147544, 4797.52024797521, 4815.109204797115, 4820.180569303218, 4361.105973275487, 4384.758838263894, 4399.802008909591, 4424.40270563473, 4510.165619360412, 4393.934090988621, 4409.669274804386, 4426.645868330542, 4431.335299705038, 4474.194644963905, 4355.956440435619, 4367.279398899178, 4434.22401079813, 4462.955370446286, 4484.372187893698, 4403.2954727243805, 4416.514183439851, 4424.380586717862, 4426.026274219666, 4430.113977204572, 4397.516273209976, 4399.956000439986, 4408.603225709701, 4411.536788637211, 4413.139437809628, 4711.0, 4721.3390125382475, 4777.641676874214, 4795.6882802618065, 4798.040391921595, 5104.719240441774, 5105.591552675777, 5112.386513618352, 5158.200478925773, 5161.406438259573, 5135.897282054375, 5143.254228136937, 5160.690358578459, 5166.793328266903, 5185.740712964337, 4611.469680986729, 4613.238815595456, 4646.0, 4731.1720448921305, 4786.353842231324, 5092.6180536459915, 5112.795488180505, 5119.539241468211, 5125.384953805567, 5166.2508936204795, 4850.6362022847725, 4873.780679869415, 4882.389701287339, 4897.461279259279, 4936.7284799336385, 4966.627502937295, 4982.10322142018, 4982.8754281143265, 4986.90026199474, 4993.525615066563, 4959.231319145466, 4976.477469865685, 5009.373828271466, 5030.622703297267, 5074.898502029939, 5006.924896126488, 5020.246962955513, 5023.673461225082, 5029.1450453423, 5075.0357432087785, 4163.958360416357, 4191.8742437726305, 4201.34879093741, 4205.474315710536, 4251.87244382669, 3607.0801945503727, 3637.727170462226, 3659.341318562631, 3665.8533658653355, 3711.31340701971, 4504.572065653758, 4506.369108324837, 4506.954930450654, 4507.954920450819, 4554.863354099382, 5205.167173252262, 5218.921716174261, 5221.7128057956415, 5226.059309324282, 5261.789528418897, 4335.263005289045, 4362.541933096979, 4443.266860967969, 4478.462584489882, 4485.48416932057, 4626.467956185014, 4639.211334073216, 4643.976780116078, 4669.159551280734, 4688.789004494738, 4871.780769865364, 4876.95123048765, 4884.731339776346, 4885.731284779229, 4928.852134435972, 5011.273365361929, 5040.949590504048, 5042.546170844642, 5055.747212639427, 5192.870178245574, 4779.354787103696, 4815.2054910939305, 4815.590674792682, 4816.34979277793, 4817.927731083966, 5007.47421520735, 5010.649254552292, 5013.573846223112, 5023.0, 5030.446650868328, 5014.974925125351, 5054.646174767731, 5064.518870707277, 5072.644914856072, 5106.438291787975, 4934.259861020875, 4946.777395017305, 4949.133901567283, 4953.405591329064, 4997.475265097112, 4895.510448955076, 4949.183384741621, 4950.554450099508, 4951.529604687549, 4997.950020499894, 4867.342908707279, 4912.287718280901, 4917.631177661761, 4926.359573255597, 4959.107360675113, 5026.095302845523, 5036.194208926554, 5038.546530812097, 5050.696958182521, 5085.567726743268, 4950.0, 4952.133376658998, 4953.72754498506, 4957.206846904478, 5004.249362595494], "value": 4673.791358678552, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [528.1417696243603, 552.6628756458559, 554.1051202308273, 556.9693666848324, 560.990217608305, 28787.856060719692, 31426.371472570536, 31467.68532314676, 31473.05580832573, 31499.21251968702, 31082.378352432996, 39162.60837391632, 41214.175716485726, 41444.17111657765, 44949.32576011354, 7648.770536883902, 7788.1043679976965, 7788.922110778904, 7797.415193860455, 7798.532088074734, 7700.268474494914, 7737.458377913548, 7800.492967957093, 7801.8049548761455, 7805.1804560520895, 7630.2369763023535, 7790.0, 9844.3601165924, 10088.192944564455, 10103.494825258707, 9979.051990060969, 10062.547185376603, 10072.94963525185, 10082.546285417175, 10086.798264034715, 9813.264005199639, 9877.06167914047, 9896.505174741234, 9905.2571057171, 9909.405435673883, 36820.0, 37021.07447313812, 37143.25713485742, 37151.0, 37175.81412092949, 3752.362098443244, 3781.149241420686, 3785.8296376662843, 3813.199228162089, 3821.789801560913, 27614.861925690242, 27982.44035119286, 28066.298342541395, 28081.297967550974, 29352.412951740847, 3441.741869359808, 3554.9466757998653, 3566.0906468850535, 3592.0481072515804, 3598.7300952428673, 236291.0, 237437.0, 237491.0, 237996.0, 239271.80364098243, 51694.22458663123, 52042.47957520452, 52065.21902171471, 52065.7396713014, 52065.958680826174, 54862.0, 54916.45083549193, 54985.450145498835, 55000.0, 55014.0, 12503.312317822607, 12886.871131288755, 12890.0, 12891.871081289068, 12913.354332283348, 9695.224382049419, 9740.610375584898, 9752.171065459515, 9759.853602195974, 9770.804583908282, 13949.860501394856, 14315.785263220852, 15187.494063479624, 15767.448139315069, 15856.682866342608, 5714.628549144292, 6090.786822461281, 6205.317415084337, 6211.0, 6354.904676429857, 807.4509333653173, 817.448222449844, 822.416084579943, 832.887560179368, 855.9015713193063, 26741.19776406711, 27649.0, 27749.490835030567, 28059.438811223663, 28092.85953570219, 920.3925409229876, 939.6006697153682, 955.1308309438377, 963.3786207895868, 964.8938616752151, 12444.626661200178, 13099.0, 13155.144915580457, 13189.538366157138, 13306.26815525136, 327679.0, 331641.0, 332109.0, 332309.0, 332579.0, 41888.0, 42034.57965420368, 42147.15705685869, 42169.578304217175, 42177.57822421798, 1203.0134500847907, 1211.1824518450012, 1230.975380492403, 1232.5069972011268, 1232.7596118756935, 22262.888685556467, 22264.55470890541, 23163.536729265656, 23521.0, 23530.764692353543, 35270.47094293536, 35564.0, 35640.0, 35658.64341356553, 35678.286434271686, 280983.0, 282790.0, 282809.0, 283225.0, 283877.0, 23000.654990175495, 23368.0, 23379.298621041395, 38804.6119538801, 39914.40128398018, 39728.80135599303, 41296.1740765189, 42353.0, 43162.0, 44406.55593444024, 36558.26883462263, 36709.44935825912, 37017.593856399246, 37478.71718952101, 37866.0, 36991.63008369989, 37095.814520927226, 37170.8141459291, 37202.44196337003, 37411.62588374082, 35402.11494712653, 35689.64310356863, 36400.27199456049, 36627.084322892144, 36877.63122368849, 31432.0, 33655.0, 33743.8066525675, 34213.486797698555, 34249.82875085709, 25720.742792572582, 25732.485350292514, 25829.612555812055, 25892.0, 26354.86822565875], "value": 17099.809331695677, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [341.63927262615334, 362.95644522657284, 372.09580718853175, 373.9976862009813, 401.0195073046401, 12609.936950315247, 15705.921470392646, 16069.437569685064, 17383.47849564512, 17555.297788088494, 16662.666746665058, 17017.404390846325, 17079.0, 17148.742768858476, 17166.485005449824, 5006.474320196382, 5012.874678133039, 5149.227615857632, 5188.844334669966, 5196.220566914974, 3863.150106976461, 4046.433499310099, 5119.744012799364, 5131.897362052757, 5166.948330516703, 5761.971190144064, 6019.7893073742425, 6026.427489388523, 6089.512838972893, 6101.115338275948, 5818.941810581882, 5902.852428689274, 5921.171036054956, 6075.149479072911, 6087.269527656666, 5226.895462090775, 5232.215167724834, 5236.1098613235845, 5250.212468129773, 5254.6847189168775, 20147.496312592157, 20323.288684896106, 20370.823981083842, 20423.897880510503, 20513.17947282107, 2482.7889629381525, 2538.1750930947514, 2565.3330134165253, 2607.908723194679, 2618.56793629051, 12254.632361029184, 12929.159604625669, 13045.152065115737, 13421.530246441425, 13588.184708917397, 1705.155947805844, 1757.3605681002164, 1764.1179410294853, 1803.7564928734716, 1830.9816901830945, 40501.0, 52851.47148528542, 58836.0, 63507.68246158786, 65355.01967470539, 9049.0, 9451.196648284973, 9483.857742133874, 9567.330286879851, 9689.41863488193, 12387.938060309642, 12414.689632759253, 12533.937330313473, 12534.686632834251, 12659.746805064031, 6695.363940425652, 6766.391024807693, 6902.102726645504, 7012.403945664573, 7018.649067546704, 6321.304656487782, 6371.7657333981315, 6532.0, 6590.637514936724, 6598.1422415085735, 5985.970070149621, 5999.340072592011, 6031.0, 6051.152838602599, 6213.1922850029205, 1676.4635316698539, 1712.571857035741, 1726.8532174765035, 1738.3220543987854, 1746.659401416737, 957.1003256938455, 965.4400447740294, 967.2165545907696, 980.2990861534038, 1013.2400699475394, 16332.0, 16866.0, 16897.83102168987, 17165.914170429067, 17201.483955481355, 956.086936975187, 970.0, 970.2238209432426, 971.3783178765455, 971.3928794503436, 9229.446233226028, 9481.09929556691, 9688.564014619358, 9688.70933871971, 9867.703968880945, 324780.0, 336445.0, 338240.0, 340039.0, 340223.0, 61129.694351527956, 61779.0, 62009.0, 63380.27464919005, 64324.0, 927.7216834949623, 930.4882314726873, 953.9379940303858, 954.608610469718, 956.5073986896754, 14425.134491930517, 14557.34491947886, 14785.704285914437, 14945.0, 15360.231988400312, 31397.529037064007, 31703.682963170075, 31899.36201276008, 32116.35767284688, 33196.1700957478, 40481.59518404778, 40859.0, 41018.0, 41927.161456771304, 42936.0, 7341.6696248670005, 7396.6671499783715, 7442.665080071518, 7568.962155189189, 7761.525310191271, 10673.786524269626, 10823.458827058774, 11033.282836615594, 11146.386948717898, 11916.702082447662, 9231.76920576991, 9461.668841590512, 9498.572564234764, 9823.312368134448, 9960.854501732103, 9724.124828765445, 9957.402555846673, 9999.550020249251, 10076.798464030824, 10174.389536627827, 9516.23870090405, 9528.904710952802, 9787.21702263803, 9788.510574471105, 10210.69531435812, 7336.669849856663, 7545.0, 7558.395328373607, 7580.924190758022, 7732.420068494998, 9645.71062868115, 9689.563969621526, 9840.736630574866, 9961.452120133463, 10099.0], "value": 8130.360605422391, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1311.4426368793268, 1314.2246074815857, 1318.1629665162623, 1325.8674132586737, 1336.9331533423326, 6867.381935625793, 7291.5989620570845, 7322.853542929145, 7324.194338622748, 7337.33963943245, 6406.455451286646, 6624.569402988803, 6927.653617319139, 7125.786226413215, 7132.072830532036, 2145.367116700573, 2173.89130543473, 2178.2593918067855, 2180.367693368929, 2184.759676435591, 2122.2147805312056, 2221.389117992549, 2229.2754854672216, 2231.1856172497005, 2233.6314508106143, 1897.0419937931326, 1963.342280336089, 2099.307228614561, 2169.4684802223396, 2171.7285339332584, 2119.205298013245, 2120.300300900699, 2133.9786602133936, 2142.6678864775986, 2143.2712877621607, 2177.858439201447, 2195.1438938813876, 2217.4788924602703, 2218.0129842220217, 2220.5336879255374, 7197.352238298578, 7267.454940879402, 7272.454565907578, 7273.199948005715, 7273.34539891407, 4286.185624731291, 4425.8229670813125, 4427.424434823484, 4429.601335879786, 4431.379606855043, 2841.176058942904, 3029.7576193904656, 3048.0551029181024, 3048.2531779714113, 3062.525308577174, 1081.1891081688734, 1144.9427528623535, 1169.7952858249857, 1176.8999635030948, 1211.8485189351331, 9136.314776391797, 9191.31065170115, 9253.02843201461, 9258.907410925873, 9284.628614855397, 3966.7421617594764, 3973.6821054315587, 3999.4400783889696, 4177.373393990865, 4194.895127621834, 7984.0, 7984.600769961478, 7986.0, 7988.760337189894, 7992.600369981477, 4452.844150454784, 4461.643068554507, 4461.821527138943, 4462.1075784842515, 4464.241079016575, 3975.7614543127506, 4065.5324637666454, 4074.490688663917, 4074.694397920168, 4079.8980025499004, 3217.8551965161623, 3237.109794806447, 3310.8344582770765, 3317.867285308609, 3331.93336133276, 1005.3264312910368, 1014.6347314966606, 1023.5905637744813, 1029.2229366828146, 1047.7328281288223, 687.5290426058166, 692.7990882643977, 695.1727444341319, 697.7034760226883, 714.5462631229207, 4594.885127871763, 4740.644451666139, 4772.546608072227, 4862.610991120702, 5033.345665063518, 525.406290891298, 654.0973456629915, 821.7165078048064, 913.6756451459636, 918.0589895357313, 3242.7567932405163, 3317.3862835375535, 3357.1439282982683, 3492.0, 3583.7312201584464, 8351.24838764514, 8586.957065214634, 8597.269232115215, 8679.956600216958, 8791.252743516745, 8455.069942306342, 8524.445911015764, 8563.186497282748, 8609.526476043753, 8612.267957223581, 6611.305812889674, 6636.668166591651, 6763.763268285685, 6789.898151527731, 6824.112865327475, 4794.832180873653, 4796.95203047979, 4853.0779151961015, 4875.341828853059, 4932.975335123301, 7891.0, 7893.447458677838, 7902.407319450949, 7911.485753426009, 8039.959800200962, 7885.842283154419, 7996.800079998046, 8062.677492900219, 8106.229908158715, 8166.387520936072, 5888.263967004124, 6020.217371741645, 6022.488088512526, 6023.969880150571, 6026.758929642765, 6966.303369662993, 6986.056882320821, 6992.895106573304, 7000.0, 7003.509754317149, 6065.757369705162, 6299.08663243818, 6301.779437719658, 6366.4588509977175, 6398.072279519351, 6186.752529898754, 6197.4732147767945, 6212.81361559154, 6218.782342617987, 6237.688115594293, 5751.7411716472025, 5850.619709718854, 5861.648301101947, 5863.20846685692, 5878.118282257525, 4253.532111467798, 4283.914321713611, 4309.719868208556, 4333.523312435693, 4424.867253982386, 4256.700083875943, 4262.531121576562, 4273.91452170961, 4300.118475712534, 4448.266036104006], "value": 3871.877325010195, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [336.57423359450297, 350.75972958523414, 352.1478023183895, 354.1659392131532, 362.0026826094112, 4699.553542413474, 4799.208130658437, 4821.156297647911, 4901.33831932689, 4945.615227736231, 4611.331356953248, 4693.554112359329, 4697.6241900647865, 4753.857384278468, 4806.0, 3831.578526362098, 3837.4819399381145, 3849.7305188636806, 3857.842647205848, 3859.8456061757497, 3662.597114317423, 3787.602301758317, 3862.7832232846563, 3869.1487872667967, 3871.8064096795188, 3755.6619904208615, 3789.5263092113487, 3824.1778017726183, 3826.9043273918237, 3828.5022947016987, 3830.674392676598, 3834.8082595870096, 3840.4047372657005, 3848.692104631623, 3849.942250866239, 3432.1591210153424, 3452.637473065317, 3458.4466485362473, 3463.428534291864, 3465.1163953192026, 4620.884477888079, 4650.372199753057, 4660.627149828039, 4701.106789709944, 4740.691855029412, 4347.847825326098, 4361.978190109061, 4380.956190438087, 4408.713433626804, 4409.536998615164, 5014.821516943515, 5040.621953353513, 5060.5951523878, 5072.898542029176, 5088.516590923857, 3703.925921481555, 3754.6808521275725, 3762.6801721853676, 3768.4347347898033, 3785.7885476647484, 5246.2655228267695, 5247.921281180786, 5253.347059734848, 5253.737313134367, 5276.4459731728, 3847.5382954045695, 3853.190829925747, 3854.094287842355, 3854.8843534694006, 3859.7877116758846, 3859.421086836941, 3883.805809709503, 3917.6474117329576, 3919.2945269851703, 3931.9213615727526, 4232.597903199191, 4340.739555626673, 4345.9130817383475, 4368.759718215465, 4391.736495810198, 4988.825391111293, 5000.324956130876, 5003.874903127378, 5009.24861270805, 5049.166887463527, 1276.3745764575476, 1287.2340957130493, 1298.9025823063307, 1333.8132661427408, 1342.7985802129563, 933.3699752666922, 960.4573416020066, 961.6826447272383, 964.5611246882652, 966.6713317472094, 4271.017665936852, 4285.292926667127, 4291.506476755213, 4307.612314891675, 4332.068605249887, 4638.24040131202, 4905.509449055137, 4932.334134891744, 4962.568341512633, 4982.928670335895, 4254.681198746691, 4301.720388174758, 4317.0, 4320.222359975234, 4335.263005289108, 2662.9481354865106, 2669.172556507489, 2674.852883091449, 2679.834272091646, 2682.3964607963444, 4609.907801843944, 4756.453007904066, 4909.852704418873, 4926.778294976663, 4935.555799978019, 5280.877548777679, 5301.6818990860675, 5301.920471192935, 5329.147336426153, 5342.492463215988, 4892.902141957141, 4895.4370247422, 4935.580475659537, 4948.331975183376, 4958.801647934042, 5063.443021267584, 5181.274621553061, 5212.50481204285, 5220.451852555427, 5225.0144944920685, 5079.72061536619, 5083.46623604531, 5095.337606111182, 5098.923516147335, 5111.131107711623, 4762.309465127468, 4906.282801019614, 4917.0, 4933.235348520985, 4954.702717836942, 3576.7496275260482, 3649.2883887641656, 3651.3245049665898, 3654.25087856994, 3671.5043469131324, 5149.227615857501, 5160.870978225575, 5171.095058364695, 5185.092608793521, 5185.729496273287, 5040.395152581567, 5051.823186188466, 5104.540591346797, 5106.7446627669215, 5113.335266415343, 4818.277258411266, 5024.472430394756, 5048.192289233705, 5065.594752419724, 5075.340205773374, 4997.875478017562, 5005.549500544968, 5013.623978201576, 5024.296598476143, 5025.723585202849, 4853.52470240173, 5042.092423363683, 5100.69395836251, 5109.284700142058, 5113.821016264413, 4949.678270912379, 4956.281339205867, 4965.4289756678445, 4970.0, 4972.712552226017], "value": 3848.503787682661, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [665.2017578905314, 686.9175698916131, 687.5015613680081, 698.7833771530823, 709.9254578269282, 12599.622011339663, 13560.932195339034, 13589.592312230625, 13592.932035339822, 13598.796018059724, 12101.394930253475, 12102.334371609559, 12126.151169418144, 12153.149279550435, 12205.389730513483, 673.2358772792891, 691.2223748283182, 802.6187560908551, 809.1988930958338, 819.9344052475788, 819.5082950229844, 820.8071103290722, 821.7165078048064, 836.2640876029117, 836.6277006732007, 868.4311775786838, 870.2863651805523, 871.1071152069148, 872.3675335381861, 876.2989608313355, 706.3113464372248, 728.6429649471773, 834.7454026521908, 873.6068769053887, 873.7990262239689, 629.7984644913629, 630.1335663462738, 631.0282165465178, 631.1290419221446, 632.6994677528184, 13229.801552976713, 13296.0, 13296.734065318737, 13302.401391937387, 13313.53402630913, 13082.607521774362, 13110.803337949937, 13111.672208194776, 13154.473821047146, 13197.538086166938, 11959.222650527689, 12049.216800907912, 12089.214201076902, 12566.434510446961, 12647.747045059139, 4139.551157095023, 4218.78906054696, 4239.51245606753, 4245.915081698379, 4379.102284031797, 62365.0, 63354.36645633577, 63882.680586597235, 64670.70658586849, 65194.0, 10311.381317120997, 10334.431606261578, 10376.221783366278, 10578.52396642153, 10618.734531636617, 19899.502512437306, 19914.203431862563, 20104.89947550282, 20105.497362566053, 20106.899465502873, 6426.518011149183, 6464.644444555594, 6475.7733479328, 6479.157709497735, 6589.406953374123, 3679.5952445231005, 3686.705063594906, 3696.8336424860945, 3739.682127019234, 3740.663340299386, 2716.2258756254373, 2771.1270949650793, 2774.542200536929, 2779.3190668285997, 2790.7209279072335, 472.88887111528766, 483.97338146402285, 487.12317827909624, 491.1723745488863, 495.35109007199964, 529.8331025726884, 560.3863769172749, 566.164906762522, 572.4418691775569, 593.4777395891588, 7376.7786966391095, 7570.735024274234, 7596.316331530189, 7796.4932279401655, 7902.3678105751405, 533.0884188038498, 548.2653244652186, 569.663898300005, 582.8601135727395, 585.8916100521417, 3910.2179564087296, 3970.50368703912, 3974.82113304902, 4077.571854955246, 4094.119764250641, 62919.37080629227, 64504.35495645077, 65436.34563654398, 66669.0, 67142.0, 54617.453825462035, 54691.72654136704, 55387.0, 55905.7204713982, 56769.71615141898, 40588.59411405907, 43266.7836660821, 43330.35004474936, 45821.0, 47562.286565702234, 8189.50862948225, 8526.232639062266, 8526.829463410573, 8528.403011789114, 8579.82840343202, 31714.524282136248, 33075.173120672174, 33121.18469930083, 33917.8128765492, 34111.0, 38807.0, 39755.40366894557, 39998.80000599979, 42495.362569560864, 46672.53327466682, 9425.19885809714, 9574.234061275221, 9597.952010239904, 11136.610218642632, 12048.879511204776, 10974.683355758858, 11009.229353945148, 11385.886141138813, 11411.486483108114, 12351.629451116482, 12393.0, 12915.870841291468, 12966.286854223108, 12994.415251313525, 13264.137831040951, 11906.702332441759, 12090.818637720266, 12192.634220973385, 13063.0, 13581.049326547047, 11643.243189192675, 11724.70688232801, 12789.0, 12809.487620495076, 12823.551175708806, 10081.747956301151, 10245.487725613839, 10246.743831404274, 10436.895631043591, 10546.57813687444, 9447.958768210025, 10012.599496020077, 10504.369737815756, 10535.57857685684, 10637.0], "value": 6289.357418113587, "unit": "score"}], "name": "dromaeo_css", "value": 6272.491620627665, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 114.0, 115.0, 114.0, 116.0, 114.0, 115.0, 115.0, 116.0, 114.0], "value": 113.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 129.0, 127.0, 164.0, 133.0, 128.0, 127.0, 127.0, 158.0, 127.0], "value": 137.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 164.0, 162.0, 164.0, 162.0, 162.0, 160.0, 161.0, 162.0], "value": 161.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 75.0, 109.0, 77.0, 77.0, 77.0, 77.0, 92.0, 75.0], "value": 81.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 111.0, 86.0, 85.0, 85.0, 97.0, 85.0, 84.0, 109.0], "value": 91.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 150.0, 149.0, 149.0, 149.0, 150.0, 150.0, 150.0, 149.0, 149.0], "value": 149.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 60.0, 61.0, 60.0, 61.0, 61.0, 62.0, 61.0, 62.0, 61.0], "value": 61.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 67.0, 67.0, 67.0, 66.0, 66.0, 67.0, 68.0, 68.0], "value": 67.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 85.0, 95.0, 89.0, 92.0, 85.0, 88.0, 85.0, 84.0], "value": 87.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 139.0, 142.0, 134.0, 132.0, 132.0, 141.0, 134.0, 223.0, 132.0], "value": 145.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 179.0, 184.0, 177.0, 179.0, 176.0, 179.0, 179.0, 182.0, 177.0], "value": 179.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 70.0, 69.0, 76.0, 69.0, 70.0, 70.0, 75.0, 68.0], "value": 70.5, "unit": "score"}], "name": "kraken", "value": 1557.5999999999997, "alertThreshold": 2.0}]} 12:14:43 INFO - Return code: 0 12:14:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:14:43 INFO - # TBPL SUCCESS # 12:14:43 INFO - Running post-action listener: _resource_record_post_action 12:14:43 INFO - [mozharness: 2017-01-01 20:14:43.343891Z] Finished run-tests step (success) 12:14:43 INFO - Running post-run listener: _resource_record_post_run 12:14:43 INFO - Total resource usage - Wall time: 1202s; CPU: 13.0%; Read bytes: 8192; Write bytes: 393351168; Read time: 24; Write time: 242972 12:14:43 INFO - TinderboxPrint: CPU usage
13.1% 12:14:43 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 24 12:14:43 INFO - TinderboxPrint: I/O write bytes / time
393,351,168 / 242,972 12:14:43 INFO - TinderboxPrint: CPU idle
8,338.3 (86.9%) 12:14:43 INFO - TinderboxPrint: CPU user
1,211.3 (12.6%) 12:14:43 INFO - TinderboxPrint: Swap in / out
0 / 0 12:14:43 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 110497792; Read time: 0; Write time: 60340 12:14:43 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 8192; Write bytes: 281956352; Read time: 24; Write time: 182608 12:14:43 INFO - Running post-run listener: _upload_blobber_files 12:14:43 INFO - Blob upload gear active. 12:14:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:14:43 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 12:14:43 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'] 12:14:43 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 12:14:44 INFO - (blobuploader) - INFO - Open directory for files ... 12:14:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:14:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:14:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:14:45 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:14:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:14:45 INFO - (blobuploader) - INFO - Done attempting. 12:14:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:14:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:14:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:14:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:14:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:14:46 INFO - (blobuploader) - INFO - Done attempting. 12:14:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:14:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:14:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:14:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:14:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:14:49 INFO - (blobuploader) - INFO - Done attempting. 12:14:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:14:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:14:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:14:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:14:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:14:50 INFO - (blobuploader) - INFO - Done attempting. 12:14:50 INFO - (blobuploader) - INFO - Iteration through files over. 12:14:50 INFO - Return code: 0 12:14:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:14:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:14:50 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/139089bba0dda8ffb63529afcc3299622a059457cb9d92eb31875f61a3ef3b60299d2a612631b632f6d1d6f561dbf5ab386ef733a97291ea19e25e7ef6cd6a71", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a5472c46d463f571e35bf8358846a27b37b743b1bd739efc5c66eac038a382f703c4aed2f1b5fad18c4822ad590c349ab6aaec7f453b04cdb4017bddff71f3c5", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ba03532964f5867762c0159c64ae24c98b785ab89cfd38845c40b03109e37f6f9cd2f1a39eab7630cd5f71cde452ac30a4952122543b33cc0568fc1251ba3695", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9c723389a7a59c13b0b5bdf05b030bda99249f2f7c47628e24a7e8eacf2390f1ecc22dfcaa6e5242d5d81db3794b7d9e176f704f6f35cf8151b8cb830e4ebed0"} 12:14:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:14:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:14:50 INFO - Contents: 12:14:50 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/139089bba0dda8ffb63529afcc3299622a059457cb9d92eb31875f61a3ef3b60299d2a612631b632f6d1d6f561dbf5ab386ef733a97291ea19e25e7ef6cd6a71", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a5472c46d463f571e35bf8358846a27b37b743b1bd739efc5c66eac038a382f703c4aed2f1b5fad18c4822ad590c349ab6aaec7f453b04cdb4017bddff71f3c5", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ba03532964f5867762c0159c64ae24c98b785ab89cfd38845c40b03109e37f6f9cd2f1a39eab7630cd5f71cde452ac30a4952122543b33cc0568fc1251ba3695", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9c723389a7a59c13b0b5bdf05b030bda99249f2f7c47628e24a7e8eacf2390f1ecc22dfcaa6e5242d5d81db3794b7d9e176f704f6f35cf8151b8cb830e4ebed0"} 12:14:50 INFO - Running post-run listener: copy_logs_to_upload_dir 12:14:50 INFO - Copying logs to upload dir... 12:14:50 INFO - mkdir: /builds/slave/test/build/upload/logs 12:14:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1246.747947 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2017-01-01 12:14:49.543087) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-01 12:14:49.546528) ========= 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=826874ccc97bd7bf541b4875000001d5-1483286196.284817-156018094 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/139089bba0dda8ffb63529afcc3299622a059457cb9d92eb31875f61a3ef3b60299d2a612631b632f6d1d6f561dbf5ab386ef733a97291ea19e25e7ef6cd6a71", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a5472c46d463f571e35bf8358846a27b37b743b1bd739efc5c66eac038a382f703c4aed2f1b5fad18c4822ad590c349ab6aaec7f453b04cdb4017bddff71f3c5", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ba03532964f5867762c0159c64ae24c98b785ab89cfd38845c40b03109e37f6f9cd2f1a39eab7630cd5f71cde452ac30a4952122543b33cc0568fc1251ba3695", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9c723389a7a59c13b0b5bdf05b030bda99249f2f7c47628e24a7e8eacf2390f1ecc22dfcaa6e5242d5d81db3794b7d9e176f704f6f35cf8151b8cb830e4ebed0"} build_url:https://queue.taskcluster.net/v1/task/LawvOJPnQIiDuK5Ahz4lag/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011625 build_url: 'https://queue.taskcluster.net/v1/task/LawvOJPnQIiDuK5Ahz4lag/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/139089bba0dda8ffb63529afcc3299622a059457cb9d92eb31875f61a3ef3b60299d2a612631b632f6d1d6f561dbf5ab386ef733a97291ea19e25e7ef6cd6a71", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a5472c46d463f571e35bf8358846a27b37b743b1bd739efc5c66eac038a382f703c4aed2f1b5fad18c4822ad590c349ab6aaec7f453b04cdb4017bddff71f3c5", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ba03532964f5867762c0159c64ae24c98b785ab89cfd38845c40b03109e37f6f9cd2f1a39eab7630cd5f71cde452ac30a4952122543b33cc0568fc1251ba3695", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9c723389a7a59c13b0b5bdf05b030bda99249f2f7c47628e24a7e8eacf2390f1ecc22dfcaa6e5242d5d81db3794b7d9e176f704f6f35cf8151b8cb830e4ebed0"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-01 12:14:49.572177) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-01 12:14:49.572507) ========= 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=826874ccc97bd7bf541b4875000001d5-1483286196.284817-156018094 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004461 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-01 12:14:49.658027) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-01 12:14:49.658357) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-01 12:14:49.658668) ========= ========= Total master_lag: 0.16 =========