builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-024 starttime: 1483213390.12 results: success (0) revision: cb9f43eb552570e185e95dfc8acb9f9fbe47ae17 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-31 11:43:10.121562) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-31 11:43:10.122066) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-31 11:43:10.122347) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-31 11:43:10.141356) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-31 11:43:10.141714) ========= 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=41a7e32711b8e08598a63237000001d6-1483212920.537035-964079285 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-31 11:43:10-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 112M=0s 2016-12-31 11:43:10 (112 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.103425 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-31 11:43:10.259359) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-31 11:43:10.259683) ========= 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=41a7e32711b8e08598a63237000001d6-1483212920.537035-964079285 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020683 ========= master_lag: 0.17 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-31 11:43:10.449976) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-31 11:43:10.450293) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev cb9f43eb552570e185e95dfc8acb9f9fbe47ae17 --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 cb9f43eb552570e185e95dfc8acb9f9fbe47ae17 --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=41a7e32711b8e08598a63237000001d6-1483212920.537035-964079285 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-31 11:43:10,717 truncating revision to first 12 chars 2016-12-31 11:43:10,717 Setting DEBUG logging. 2016-12-31 11:43:10,717 attempt 1/10 2016-12-31 11:43:10,717 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/cb9f43eb5525?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-31 11:43:11,234 unpacking tar archive at: mozilla-central-cb9f43eb5525/testing/mozharness/ program finished with exit code 0 elapsedTime=0.827524 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-31 11:43:11.290506) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-31 11:43:11.290826) ========= script_repo_revision: cb9f43eb552570e185e95dfc8acb9f9fbe47ae17 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-31 11:43:11.291197) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-31 11:43:11.291606) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-31 11:43:11.299784) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-12-31 11:43:11.300055) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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=41a7e32711b8e08598a63237000001d6-1483212920.537035-964079285 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:43:11 INFO - MultiFileLogger online at 20161231 11:43:11 in /builds/slave/test 11:43:11 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --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:43:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:43:11 INFO - {'append_to_log': False, 11:43:11 INFO - 'base_work_dir': '/builds/slave/test', 11:43:11 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 11:43:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:43:11 INFO - 'branch': 'Firefox-Non-PGO', 11:43:11 INFO - 'buildbot_json_path': 'buildprops.json', 11:43:11 INFO - 'config_files': ('talos/linux_config.py',), 11:43:11 INFO - 'default_actions': ('clobber', 11:43:11 INFO - 'read-buildbot-config', 11:43:11 INFO - 'download-and-extract', 11:43:11 INFO - 'populate-webroot', 11:43:11 INFO - 'create-virtualenv', 11:43:11 INFO - 'install', 11:43:11 INFO - 'run-tests'), 11:43:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:43:11 INFO - 'download_minidump_stackwalk': True, 11:43:11 INFO - 'download_symbols': 'ondemand', 11:43:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:43:11 INFO - 'tooltool.py': '/tools/tooltool.py', 11:43:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:43:11 INFO - '/tools/misc-python/virtualenv.py')}, 11:43:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:43:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:43:11 INFO - 'installer_path': 'installer.exe', 11:43:11 INFO - 'log_level': 'info', 11:43:11 INFO - 'log_name': 'talos', 11:43:11 INFO - 'log_to_console': True, 11:43:11 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:43:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:43:11 INFO - 'opt_config_files': (), 11:43:11 INFO - 'pip_index': False, 11:43:11 INFO - 'sps_profile': False, 11:43:11 INFO - 'sps_profile_interval': 0, 11:43:11 INFO - 'suite': 'dromaeojs-e10s', 11:43:11 INFO - 'system_bits': '32', 11:43:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:43:11 INFO - 'title': 'talos-linux64-ix-024', 11:43:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:43:11 INFO - 'use_talos_json': True, 11:43:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:43:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:43:11 INFO - 'work_dir': 'build'} 11:43:11 INFO - [mozharness: 2016-12-31 19:43:11.660841Z] Running clobber step. 11:43:11 INFO - Running pre-action listener: _resource_record_pre_action 11:43:11 INFO - Running main action method: clobber 11:43:11 INFO - rmtree: /builds/slave/test/build 11:43:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:43:12 INFO - Running post-action listener: _resource_record_post_action 11:43:12 INFO - [mozharness: 2016-12-31 19:43:12.122631Z] Finished clobber step (success) 11:43:12 INFO - [mozharness: 2016-12-31 19:43:12.122733Z] Running read-buildbot-config step. 11:43:12 INFO - Running pre-action listener: _resource_record_pre_action 11:43:12 INFO - Running main action method: read_buildbot_config 11:43:12 INFO - Using buildbot properties: 11:43:12 INFO - { 11:43:12 INFO - "project": "", 11:43:12 INFO - "product": "firefox", 11:43:12 INFO - "who": "philringnalda@gmail.com", 11:43:12 INFO - "installer_path": "public/build/target.tar.bz2", 11:43:12 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 11:43:12 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 11:43:12 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 11:43:12 INFO - "stage_platform": "linux64", 11:43:12 INFO - "basedir": "/builds/slave/test", 11:43:12 INFO - "buildnumber": 594, 11:43:12 INFO - "platform": "ubuntu64_hw", 11:43:12 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 11:43:12 INFO - "slavebuilddir": "test", 11:43:12 INFO - "taskId": "Bk5MozWxS0W2Uv1xXf7VWg", 11:43:12 INFO - "branch": "mozilla-central", 11:43:12 INFO - "script_repo_revision": "production", 11:43:12 INFO - "revision": "cb9f43eb552570e185e95dfc8acb9f9fbe47ae17", 11:43:12 INFO - "slavename": "talos-linux64-ix-024", 11:43:12 INFO - "repo_path": "mozilla-central" 11:43:12 INFO - } 11:43:12 INFO - Finding installer, test and symbols from parent task. 11:43:12 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Bk5MozWxS0W2Uv1xXf7VWg'}, attempt #1 11:43:12 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Bk5MozWxS0W2Uv1xXf7VWg'}, attempt #1 11:43:13 INFO - Task dependencies: Q3tBUc7hTPO9FVhiFORHuQ 11:43:13 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q3tBUc7hTPO9FVhiFORHuQ'}, attempt #1 11:43:13 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Q3tBUc7hTPO9FVhiFORHuQ/artifacts/public/build/target.tar.bz2 11:43:13 INFO - Set test_url: https://queue.taskcluster.net/v1/task/Q3tBUc7hTPO9FVhiFORHuQ/artifacts/public/build/test_packages.json 11:43:13 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Q3tBUc7hTPO9FVhiFORHuQ/artifacts/public/build/target.crashreporter-symbols.zip 11:43:13 INFO - Running post-action listener: _resource_record_post_action 11:43:13 INFO - [mozharness: 2016-12-31 19:43:13.585952Z] Finished read-buildbot-config step (success) 11:43:13 INFO - [mozharness: 2016-12-31 19:43:13.586170Z] Running download-and-extract step. 11:43:13 INFO - Running pre-action listener: _resource_record_pre_action 11:43:13 INFO - Running main action method: download_and_extract 11:43:13 INFO - mkdir: /builds/slave/test/build/tests 11:43:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:43:13 INFO - trying https://queue.taskcluster.net/v1/task/Q3tBUc7hTPO9FVhiFORHuQ/artifacts/public/build/target.test_packages.json 11:43:13 INFO - Downloading https://queue.taskcluster.net/v1/task/Q3tBUc7hTPO9FVhiFORHuQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 11:43:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q3tBUc7hTPO9FVhiFORHuQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 11:43:14 INFO - Downloaded 896 bytes. 11:43:14 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 11:43:14 INFO - Using the following test package requirements: 11:43:14 INFO - {u'common': [u'target.common.tests.zip'], 11:43:14 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 11:43:14 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 11:43:14 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 11:43:14 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 11:43:14 INFO - u'mozbase': [u'target.common.tests.zip'], 11:43:14 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 11:43:14 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 11:43:14 INFO - u'web-platform': [u'target.common.tests.zip', 11:43:14 INFO - u'target.web-platform.tests.zip'], 11:43:14 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 11:43:14 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 11:43:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Q3tBUc7hTPO9FVhiFORHuQ/artifacts/public/build/target.common.tests.zip 11:43:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q3tBUc7hTPO9FVhiFORHuQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:43:14 INFO - Fetch https://queue.taskcluster.net/v1/task/Q3tBUc7hTPO9FVhiFORHuQ/artifacts/public/build/target.common.tests.zip into memory 11:43:15 INFO - Expected file size: 20246344 11:43:15 INFO - Obtained file size: 20246344 11:43:19 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 11:43:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Q3tBUc7hTPO9FVhiFORHuQ/artifacts/public/build/target.common.tests.zip 11:43:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q3tBUc7hTPO9FVhiFORHuQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:43:19 INFO - Fetch https://queue.taskcluster.net/v1/task/Q3tBUc7hTPO9FVhiFORHuQ/artifacts/public/build/target.common.tests.zip into memory 11:43:20 INFO - Expected file size: 20246344 11:43:20 INFO - Obtained file size: 20246344 11:43:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Q3tBUc7hTPO9FVhiFORHuQ/artifacts/public/build/target.talos.tests.zip 11:43:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q3tBUc7hTPO9FVhiFORHuQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 11:43:24 INFO - Fetch https://queue.taskcluster.net/v1/task/Q3tBUc7hTPO9FVhiFORHuQ/artifacts/public/build/target.talos.tests.zip into memory 11:43:26 INFO - Expected file size: 14409776 11:43:26 INFO - Obtained file size: 14409776 11:43: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:43:26 INFO - trying https://queue.taskcluster.net/v1/task/Q3tBUc7hTPO9FVhiFORHuQ/artifacts/public/build/target.tar.bz2 11:43:26 INFO - Downloading https://queue.taskcluster.net/v1/task/Q3tBUc7hTPO9FVhiFORHuQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 11:43:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q3tBUc7hTPO9FVhiFORHuQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 11:43:28 INFO - Downloaded 55730578 bytes. 11:43:28 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Q3tBUc7hTPO9FVhiFORHuQ/artifacts/public/build/target.tar.bz2 11:43:28 INFO - mkdir: /builds/slave/test/properties 11:43:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:43:28 INFO - Writing to file /builds/slave/test/properties/build_url 11:43:28 INFO - Contents: 11:43:28 INFO - build_url:https://queue.taskcluster.net/v1/task/Q3tBUc7hTPO9FVhiFORHuQ/artifacts/public/build/target.tar.bz2 11:43:28 INFO - Running post-action listener: _resource_record_post_action 11:43:28 INFO - Running post-action listener: set_extra_try_arguments 11:43:28 INFO - [mozharness: 2016-12-31 19:43:28.758851Z] Finished download-and-extract step (success) 11:43:28 INFO - [mozharness: 2016-12-31 19:43:28.758948Z] Running populate-webroot step. 11:43:28 INFO - Running pre-action listener: _resource_record_pre_action 11:43:28 INFO - Running main action method: populate_webroot 11:43:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:43:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:43:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:43:28 INFO - '--tppagecycles', 11:43:28 INFO - '7'], 11:43:28 INFO - 'tests': ['tsvgm']}}, 11:43:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:43:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:43:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:43:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:43:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:43:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:43:28 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 11:43:28 INFO - 'talos_options': ['--disable-e10s'], 11:43:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:43:28 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:43:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:43:28 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 11:43:28 INFO - 'talos_options': ['--disable-e10s'], 11:43:28 INFO - 'tests': ['damp', 'tps']}, 11:43:28 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:43:28 INFO - 'tests': ['damp', 'tps']}, 11:43:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:43:28 INFO - 'tests': ['dromaeo_dom']}, 11:43:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:43:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:43:28 INFO - 'tests': ['basic_compositor_video']}, 11:43:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:43:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:43:28 INFO - 'tests': ['a11yr', 11:43:28 INFO - 'ts_paint', 11:43:28 INFO - 'tpaint', 11:43:28 INFO - 'sessionrestore', 11:43:28 INFO - 'sessionrestore_no_auto_restore', 11:43:28 INFO - 'tabpaint']}, 11:43:28 INFO - 'other-e10s': {'tests': ['a11yr', 11:43:28 INFO - 'ts_paint', 11:43:28 INFO - 'tpaint', 11:43:28 INFO - 'sessionrestore', 11:43:28 INFO - 'sessionrestore_no_auto_restore', 11:43:28 INFO - 'tabpaint']}, 11:43:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:43:28 INFO - 'ts_paint', 11:43:28 INFO - 'tpaint', 11:43:28 INFO - 'sessionrestore', 11:43:28 INFO - 'sessionrestore_no_auto_restore', 11:43:28 INFO - 'tabpaint']}, 11:43:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:43:28 INFO - 'ts_paint', 11:43:28 INFO - 'tpaint', 11:43:28 INFO - 'sessionrestore', 11:43:28 INFO - 'sessionrestore_no_auto_restore', 11:43:28 INFO - 'tabpaint']}, 11:43:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:43:28 INFO - 'tests': ['a11yr', 11:43:28 INFO - 'ts_paint', 11:43:28 INFO - 'tpaint', 11:43:28 INFO - 'sessionrestore', 11:43:28 INFO - 'sessionrestore_no_auto_restore', 11:43:28 INFO - 'tabpaint']}, 11:43:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:43:28 INFO - 'tests': ['a11yr', 11:43:28 INFO - 'ts_paint', 11:43:28 INFO - 'tpaint', 11:43:28 INFO - 'sessionrestore', 11:43:28 INFO - 'sessionrestore_no_auto_restore', 11:43:28 INFO - 'tabpaint']}, 11:43:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:43:28 INFO - 'tests': ['tsvgx', 11:43:28 INFO - 'tsvgr_opacity', 11:43:28 INFO - 'tart', 11:43:28 INFO - 'tscrollx', 11:43:28 INFO - 'cart', 11:43:28 INFO - 'tsvg_static']}, 11:43:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:43:28 INFO - 'tsvgr_opacity', 11:43:28 INFO - 'tart', 11:43:28 INFO - 'tscrollx', 11:43:28 INFO - 'cart', 11:43:28 INFO - 'tsvg_static']}, 11:43:28 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 11:43:28 INFO - 'talos_options': ['--disable-e10s'], 11:43:28 INFO - 'tests': ['tp5o']}, 11:43:28 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:43:28 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 11:43:28 INFO - 'talos_options': ['--disable-e10s', 11:43:28 INFO - '--xperf_path', 11:43:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:43:28 INFO - 'tests': ['tp5n']}, 11:43:28 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:43:28 INFO - 'talos_options': ['--xperf_path', 11:43:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:43:28 INFO - 'tests': ['tp5n']}}, 11:43:28 INFO - 'talos.zip': {'path': '', 11:43:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:43:28 INFO - Running post-action listener: _resource_record_post_action 11:43:28 INFO - [mozharness: 2016-12-31 19:43:28.772211Z] Finished populate-webroot step (success) 11:43:28 INFO - [mozharness: 2016-12-31 19:43:28.772302Z] Running create-virtualenv step. 11:43:28 INFO - Running pre-action listener: _resource_record_pre_action 11:43:28 INFO - Running main action method: create_virtualenv 11:43:28 INFO - Creating virtualenv /builds/slave/test/build/venv 11:43:28 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:43:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:43:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:43:28 INFO - Using real prefix '/usr' 11:43:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:43:29 INFO - Installing distribute.............................................................................................................................................................................................done. 11:43:31 INFO - Installing pip.................done. 11:43:31 INFO - Return code: 0 11:43:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:43:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:43:31 INFO - Reading from file tmpfile_stdout 11:43:31 INFO - Output received: 11:43:31 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:43:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:43:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:43:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:43:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:43:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:43:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:43:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:43:31 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 0x20151f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2126a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2387480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2384820>, '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 0x2385520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23859b0>, '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': '41a7e32711b8e08598a63237000001d6-1483212920.537035-964079285', '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:43:31 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:43:31 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:43:31 INFO - Using env: {'DISPLAY': ':0', 11:43:31 INFO - 'HOME': '/home/cltbld', 11:43:31 INFO - 'LANG': 'en_US.UTF-8', 11:43:31 INFO - 'LANGUAGE': 'en_US:en', 11:43:31 INFO - 'LOGNAME': 'cltbld', 11:43:31 INFO - 'MAIL': '/var/mail/cltbld', 11:43:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:43:31 INFO - 'MOZ_NO_REMOTE': '1', 11:43:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:43:31 INFO - 'NO_EM_RESTART': '1', 11:43:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:43:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:43:31 INFO - 'PWD': '/builds/slave/test', 11:43:31 INFO - 'SHELL': '/bin/bash', 11:43:31 INFO - 'SHLVL': '1', 11:43:31 INFO - 'TERM': 'linux', 11:43:31 INFO - 'TMOUT': '86400', 11:43:31 INFO - 'USER': 'cltbld', 11:43:31 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1483212920.537035-964079285', 11:43:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:43:31 INFO - '_': '/tools/buildbot/bin/python'} 11:43:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:43:31 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:43:31 INFO - Cleaning up... 11:43:31 INFO - Return code: 0 11:43:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:43:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:43:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:43:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:43:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:43:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:43:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:43:31 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 0x20151f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2126a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2387480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2384820>, '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 0x2385520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23859b0>, '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': '41a7e32711b8e08598a63237000001d6-1483212920.537035-964079285', '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:43:31 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:43:31 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:43:31 INFO - Using env: {'DISPLAY': ':0', 11:43:31 INFO - 'HOME': '/home/cltbld', 11:43:31 INFO - 'LANG': 'en_US.UTF-8', 11:43:31 INFO - 'LANGUAGE': 'en_US:en', 11:43:31 INFO - 'LOGNAME': 'cltbld', 11:43:31 INFO - 'MAIL': '/var/mail/cltbld', 11:43:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:43:31 INFO - 'MOZ_NO_REMOTE': '1', 11:43:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:43:31 INFO - 'NO_EM_RESTART': '1', 11:43:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:43:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:43:31 INFO - 'PWD': '/builds/slave/test', 11:43:31 INFO - 'SHELL': '/bin/bash', 11:43:31 INFO - 'SHLVL': '1', 11:43:31 INFO - 'TERM': 'linux', 11:43:31 INFO - 'TMOUT': '86400', 11:43:31 INFO - 'USER': 'cltbld', 11:43:31 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1483212920.537035-964079285', 11:43:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:43:31 INFO - '_': '/tools/buildbot/bin/python'} 11:43:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:43:31 INFO - Downloading/unpacking psutil>=3.1.1 11:43: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:43: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:43: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:43: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:43: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:43: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:43:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:43:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:43:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:43:34 INFO - Installing collected packages: psutil 11:43:34 INFO - Running setup.py install for psutil 11:43:34 INFO - building 'psutil._psutil_linux' extension 11:43:34 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:43:34 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:43:34 INFO - building 'psutil._psutil_posix' extension 11:43:34 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:43:34 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:43:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:43:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:43:34 INFO - Successfully installed psutil 11:43:34 INFO - Cleaning up... 11:43:34 INFO - Return code: 0 11:43:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:43:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:43:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:43:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:43:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:43:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:43:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:43:34 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 0x20151f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2126a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2387480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2384820>, '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 0x2385520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23859b0>, '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': '41a7e32711b8e08598a63237000001d6-1483212920.537035-964079285', '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:43:34 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:43:34 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:43:34 INFO - Using env: {'DISPLAY': ':0', 11:43:34 INFO - 'HOME': '/home/cltbld', 11:43:34 INFO - 'LANG': 'en_US.UTF-8', 11:43:34 INFO - 'LANGUAGE': 'en_US:en', 11:43:34 INFO - 'LOGNAME': 'cltbld', 11:43:34 INFO - 'MAIL': '/var/mail/cltbld', 11:43:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:43:34 INFO - 'MOZ_NO_REMOTE': '1', 11:43:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:43:34 INFO - 'NO_EM_RESTART': '1', 11:43:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:43:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:43:34 INFO - 'PWD': '/builds/slave/test', 11:43:34 INFO - 'SHELL': '/bin/bash', 11:43:34 INFO - 'SHLVL': '1', 11:43:34 INFO - 'TERM': 'linux', 11:43:34 INFO - 'TMOUT': '86400', 11:43:34 INFO - 'USER': 'cltbld', 11:43:34 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1483212920.537035-964079285', 11:43:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:43:34 INFO - '_': '/tools/buildbot/bin/python'} 11:43:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:43:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:43:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:43:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:43:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:43:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:43:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:43:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:43:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:43:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:43:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:43:36 INFO - Installing collected packages: mozsystemmonitor 11:43:36 INFO - Running setup.py install for mozsystemmonitor 11:43:37 INFO - Successfully installed mozsystemmonitor 11:43:37 INFO - Cleaning up... 11:43:37 INFO - Return code: 0 11:43:37 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:43:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:43:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:43:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:43:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:43:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:43:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:43:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20151f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2126a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2387480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2384820>, '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 0x2385520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23859b0>, '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': '41a7e32711b8e08598a63237000001d6-1483212920.537035-964079285', '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:43:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 11:43:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 11:43:37 INFO - Using env: {'DISPLAY': ':0', 11:43:37 INFO - 'HOME': '/home/cltbld', 11:43:37 INFO - 'LANG': 'en_US.UTF-8', 11:43:37 INFO - 'LANGUAGE': 'en_US:en', 11:43:37 INFO - 'LOGNAME': 'cltbld', 11:43:37 INFO - 'MAIL': '/var/mail/cltbld', 11:43:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:43:37 INFO - 'MOZ_NO_REMOTE': '1', 11:43:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:43:37 INFO - 'NO_EM_RESTART': '1', 11:43:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:43:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:43:37 INFO - 'PWD': '/builds/slave/test', 11:43:37 INFO - 'SHELL': '/bin/bash', 11:43:37 INFO - 'SHLVL': '1', 11:43:37 INFO - 'TERM': 'linux', 11:43:37 INFO - 'TMOUT': '86400', 11:43:37 INFO - 'USER': 'cltbld', 11:43:37 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1483212920.537035-964079285', 11:43:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:43:37 INFO - '_': '/tools/buildbot/bin/python'} 11:43:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:43:37 INFO - Downloading/unpacking jsonschema==2.5.1 11:43: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:43: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:43: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:43: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:43: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:43: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:43:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:43:39 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:43:39 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:43:39 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:43:39 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:43:39 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:43:39 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:43:39 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:43:39 INFO - Downloading functools32-3.2.3-2.tar.gz 11:43:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:43:39 INFO - warning: no files found matching '*.txt' 11:43:39 INFO - no previously-included directories found matching 'build' 11:43:39 INFO - no previously-included directories found matching 'dist' 11:43:39 INFO - no previously-included directories found matching '.git*' 11:43:39 INFO - Installing collected packages: jsonschema, functools32 11:43:39 INFO - Running setup.py install for functools32 11:43:39 INFO - warning: no files found matching '*.txt' 11:43:39 INFO - no previously-included directories found matching 'build' 11:43:39 INFO - no previously-included directories found matching 'dist' 11:43:39 INFO - no previously-included directories found matching '.git*' 11:43:39 INFO - Successfully installed jsonschema functools32 11:43:39 INFO - Cleaning up... 11:43:39 INFO - Return code: 0 11:43:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:43:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:43:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:43:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:43:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:43:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:43:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:43:39 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 0x20151f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2126a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2387480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2384820>, '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 0x2385520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23859b0>, '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': '41a7e32711b8e08598a63237000001d6-1483212920.537035-964079285', '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:43:39 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:43:39 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:43:39 INFO - Using env: {'DISPLAY': ':0', 11:43:39 INFO - 'HOME': '/home/cltbld', 11:43:39 INFO - 'LANG': 'en_US.UTF-8', 11:43:39 INFO - 'LANGUAGE': 'en_US:en', 11:43:39 INFO - 'LOGNAME': 'cltbld', 11:43:39 INFO - 'MAIL': '/var/mail/cltbld', 11:43:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:43:39 INFO - 'MOZ_NO_REMOTE': '1', 11:43:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:43:39 INFO - 'NO_EM_RESTART': '1', 11:43:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:43:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:43:39 INFO - 'PWD': '/builds/slave/test', 11:43:39 INFO - 'SHELL': '/bin/bash', 11:43:39 INFO - 'SHLVL': '1', 11:43:39 INFO - 'TERM': 'linux', 11:43:39 INFO - 'TMOUT': '86400', 11:43:39 INFO - 'USER': 'cltbld', 11:43:39 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1483212920.537035-964079285', 11:43:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:43:39 INFO - '_': '/tools/buildbot/bin/python'} 11:43:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:43:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:43:39 INFO - Cleaning up... 11:43:40 INFO - Return code: 0 11:43:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:43: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:43:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:43:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:43: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:43:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:43:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:43: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', '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 0x20151f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2126a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2387480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2384820>, '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 0x2385520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23859b0>, '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': '41a7e32711b8e08598a63237000001d6-1483212920.537035-964079285', '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:43: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:43: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 blobuploader==1.2.4 11:43:40 INFO - Using env: {'DISPLAY': ':0', 11:43:40 INFO - 'HOME': '/home/cltbld', 11:43:40 INFO - 'LANG': 'en_US.UTF-8', 11:43:40 INFO - 'LANGUAGE': 'en_US:en', 11:43:40 INFO - 'LOGNAME': 'cltbld', 11:43:40 INFO - 'MAIL': '/var/mail/cltbld', 11:43:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:43:40 INFO - 'MOZ_NO_REMOTE': '1', 11:43:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:43:40 INFO - 'NO_EM_RESTART': '1', 11:43:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:43:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:43:40 INFO - 'PWD': '/builds/slave/test', 11:43:40 INFO - 'SHELL': '/bin/bash', 11:43:40 INFO - 'SHLVL': '1', 11:43:40 INFO - 'TERM': 'linux', 11:43:40 INFO - 'TMOUT': '86400', 11:43:40 INFO - 'USER': 'cltbld', 11:43:40 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1483212920.537035-964079285', 11:43:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:43:40 INFO - '_': '/tools/buildbot/bin/python'} 11:43:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:43:40 INFO - Downloading/unpacking blobuploader==1.2.4 11:43:40 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:43:40 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:43:40 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:43:40 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:43:40 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:43:40 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:43:42 INFO - Downloading blobuploader-1.2.4.tar.gz 11:43:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:43:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:43:42 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:43:42 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:43:42 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:43:42 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:43:42 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:43:42 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:43:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:43:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:43:42 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:43:42 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:43:42 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:43:42 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:43:42 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:43:42 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:43:42 INFO - Downloading docopt-0.6.1.tar.gz 11:43:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:43:43 INFO - Installing collected packages: blobuploader, requests, docopt 11:43:43 INFO - Running setup.py install for blobuploader 11:43:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:43:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:43:43 INFO - Running setup.py install for requests 11:43:43 INFO - Running setup.py install for docopt 11:43:43 INFO - Successfully installed blobuploader requests docopt 11:43:43 INFO - Cleaning up... 11:43:43 INFO - Return code: 0 11:43:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:43:43 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:43:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:43:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:43:43 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:43:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:43:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:43:43 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 0x20151f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2126a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2387480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2384820>, '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 0x2385520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23859b0>, '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': '41a7e32711b8e08598a63237000001d6-1483212920.537035-964079285', '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:43:43 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:43:43 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:43:43 INFO - Using env: {'DISPLAY': ':0', 11:43:43 INFO - 'HOME': '/home/cltbld', 11:43:43 INFO - 'LANG': 'en_US.UTF-8', 11:43:43 INFO - 'LANGUAGE': 'en_US:en', 11:43:43 INFO - 'LOGNAME': 'cltbld', 11:43:43 INFO - 'MAIL': '/var/mail/cltbld', 11:43:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:43:43 INFO - 'MOZ_NO_REMOTE': '1', 11:43:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:43:43 INFO - 'NO_EM_RESTART': '1', 11:43:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:43:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:43:43 INFO - 'PWD': '/builds/slave/test', 11:43:43 INFO - 'SHELL': '/bin/bash', 11:43:43 INFO - 'SHLVL': '1', 11:43:43 INFO - 'TERM': 'linux', 11:43:43 INFO - 'TMOUT': '86400', 11:43:43 INFO - 'USER': 'cltbld', 11:43:43 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1483212920.537035-964079285', 11:43:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:43:43 INFO - '_': '/tools/buildbot/bin/python'} 11:43:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:43:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:43:43 INFO - Running setup.py (path:/tmp/pip-kBlAkw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:43:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:43:43 INFO - Running setup.py (path:/tmp/pip-A6jZC5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:43:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:43:43 INFO - Running setup.py (path:/tmp/pip-xMUXJv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:43:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:43:44 INFO - Running setup.py (path:/tmp/pip-Q21AWB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:43:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:43:44 INFO - Running setup.py (path:/tmp/pip-EzMEsK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:43:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:43:44 INFO - Running setup.py (path:/tmp/pip-hmnHkc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:43:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:43:44 INFO - Running setup.py (path:/tmp/pip-Vqx78e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:43:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:43:44 INFO - Running setup.py (path:/tmp/pip-5XDZo_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:43:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:43:44 INFO - Running setup.py (path:/tmp/pip-a_1xjR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:43:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:43:44 INFO - Running setup.py (path:/tmp/pip-HlupUa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:43:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:43:44 INFO - Running setup.py (path:/tmp/pip-g6tD1_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:43:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:43:44 INFO - Running setup.py (path:/tmp/pip-EHWhMu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:43:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:43:44 INFO - Running setup.py (path:/tmp/pip-0PTbQb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:43:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:43:44 INFO - Running setup.py (path:/tmp/pip-2v5rux-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:43:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:43:44 INFO - Running setup.py (path:/tmp/pip-2tt2dd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:43:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:43:44 INFO - Running setup.py (path:/tmp/pip-wUosq7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:43:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:43:44 INFO - Running setup.py (path:/tmp/pip-kOvWwi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:43:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:43:45 INFO - Running setup.py install for manifestparser 11:43:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:43:45 INFO - Running setup.py install for mozcrash 11:43:45 INFO - Running setup.py install for mozdebug 11:43:45 INFO - Running setup.py install for mozdevice 11:43:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:43:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:43:45 INFO - Running setup.py install for mozfile 11:43:45 INFO - Running setup.py install for mozhttpd 11:43:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:43:45 INFO - Running setup.py install for mozinfo 11:43:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:43:45 INFO - Running setup.py install for mozInstall 11:43:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:43:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:43:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:43:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:43:45 INFO - Running setup.py install for mozleak 11:43:45 INFO - Running setup.py install for mozlog 11:43:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:43:46 INFO - Running setup.py install for moznetwork 11:43:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:43:46 INFO - Running setup.py install for mozprocess 11:43:46 INFO - Running setup.py install for mozprofile 11:43:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:43:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:43:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:43:46 INFO - Running setup.py install for mozrunner 11:43:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:43:46 INFO - Running setup.py install for mozscreenshot 11:43:46 INFO - Running setup.py install for moztest 11:43:46 INFO - Running setup.py install for mozversion 11:43:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:43:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:43:46 INFO - Cleaning up... 11:43:46 INFO - Return code: 0 11:43:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:43:46 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:43:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:43:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:43:46 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:43:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:43:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:43:46 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 0x20151f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2126a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2387480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2384820>, '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 0x2385520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23859b0>, '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': '41a7e32711b8e08598a63237000001d6-1483212920.537035-964079285', '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:43:46 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:43:46 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:43:46 INFO - Using env: {'DISPLAY': ':0', 11:43:46 INFO - 'HOME': '/home/cltbld', 11:43:46 INFO - 'LANG': 'en_US.UTF-8', 11:43:46 INFO - 'LANGUAGE': 'en_US:en', 11:43:46 INFO - 'LOGNAME': 'cltbld', 11:43:46 INFO - 'MAIL': '/var/mail/cltbld', 11:43:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:43:46 INFO - 'MOZ_NO_REMOTE': '1', 11:43:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:43:46 INFO - 'NO_EM_RESTART': '1', 11:43:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:43:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:43:46 INFO - 'PWD': '/builds/slave/test', 11:43:46 INFO - 'SHELL': '/bin/bash', 11:43:46 INFO - 'SHLVL': '1', 11:43:46 INFO - 'TERM': 'linux', 11:43:46 INFO - 'TMOUT': '86400', 11:43:46 INFO - 'USER': 'cltbld', 11:43:46 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1483212920.537035-964079285', 11:43:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:43:46 INFO - '_': '/tools/buildbot/bin/python'} 11:43:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:43:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:43:47 INFO - Running setup.py (path:/tmp/pip-oPbqSX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:43:47 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:43:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:43:47 INFO - Running setup.py (path:/tmp/pip-mI1Cq2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:43:47 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:43:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:43:47 INFO - Running setup.py (path:/tmp/pip-2Iwol6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:43:47 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:43:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:43:47 INFO - Running setup.py (path:/tmp/pip-dRtCFO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:43:47 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:43:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:43:47 INFO - Running setup.py (path:/tmp/pip-0LtH26-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:43:47 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:43:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:43:47 INFO - Running setup.py (path:/tmp/pip-NMVwyy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:43:47 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:43:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:43:47 INFO - Running setup.py (path:/tmp/pip-iJr0ZJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:43:47 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:43:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:43:47 INFO - Running setup.py (path:/tmp/pip-5EXulE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:43:47 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:43:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:43:47 INFO - Running setup.py (path:/tmp/pip-dEI_x7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:43:47 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:43:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:43:47 INFO - Running setup.py (path:/tmp/pip-kAFci1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:43:47 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:43:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:43:47 INFO - Running setup.py (path:/tmp/pip-f0Bb2n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:43:47 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:43:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:43:47 INFO - Running setup.py (path:/tmp/pip-eNN0Q5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:43:47 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:43:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:43:47 INFO - Running setup.py (path:/tmp/pip-HAoivN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:43:48 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:43:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:43:48 INFO - Running setup.py (path:/tmp/pip-nwn3Pn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:43:48 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:43:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:43:48 INFO - Running setup.py (path:/tmp/pip-JYnu1H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:43:48 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:43:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:43:48 INFO - Running setup.py (path:/tmp/pip-1Wn8qh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:43:48 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:43:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:43:48 INFO - Running setup.py (path:/tmp/pip-s_SI6m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:43:48 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:43:48 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:43:48 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:43:48 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:43:48 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:43:48 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:43:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:43:48 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:43:48 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:43:48 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:43:48 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:43:48 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:43:48 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:43:50 INFO - Downloading blessings-1.6.tar.gz 11:43:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:43:50 INFO - Installing collected packages: blessings 11:43:50 INFO - Running setup.py install for blessings 11:43:50 INFO - Successfully installed blessings 11:43:50 INFO - Cleaning up... 11:43:50 INFO - Return code: 0 11:43:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:43:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:43:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:43:50 INFO - Reading from file tmpfile_stdout 11:43:50 INFO - Current package versions: 11:43:50 INFO - argparse == 1.2.1 11:43:50 INFO - blessings == 1.6 11:43:50 INFO - blobuploader == 1.2.4 11:43:50 INFO - docopt == 0.6.1 11:43:50 INFO - functools32 == 3.2.3-2 11:43:50 INFO - jsonschema == 2.5.1 11:43:50 INFO - manifestparser == 1.1 11:43:50 INFO - mozInstall == 1.12 11:43:50 INFO - mozcrash == 1.0 11:43:50 INFO - mozdebug == 0.1 11:43:50 INFO - mozdevice == 0.49 11:43:50 INFO - mozfile == 1.2 11:43:50 INFO - mozhttpd == 0.7 11:43:50 INFO - mozinfo == 0.9 11:43:50 INFO - mozleak == 0.1 11:43:50 INFO - mozlog == 3.4 11:43:50 INFO - moznetwork == 0.27 11:43:50 INFO - mozprocess == 0.23 11:43:50 INFO - mozprofile == 0.28 11:43:50 INFO - mozrunner == 6.13 11:43:50 INFO - mozscreenshot == 0.1 11:43:50 INFO - mozsystemmonitor == 0.3 11:43:50 INFO - moztest == 0.8 11:43:50 INFO - mozversion == 1.4 11:43:50 INFO - psutil == 3.1.1 11:43:50 INFO - requests == 1.2.3 11:43:50 INFO - wsgiref == 0.1.2 11:43:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:43:50 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:43:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:43:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:43:50 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:43:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:43:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:43:50 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 0x20151f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2126a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2387480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2384820>, '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 0x2385520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23859b0>, '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': '41a7e32711b8e08598a63237000001d6-1483212920.537035-964079285', '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:43:50 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:43:50 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:43:50 INFO - Using env: {'DISPLAY': ':0', 11:43:50 INFO - 'HOME': '/home/cltbld', 11:43:50 INFO - 'LANG': 'en_US.UTF-8', 11:43:50 INFO - 'LANGUAGE': 'en_US:en', 11:43:50 INFO - 'LOGNAME': 'cltbld', 11:43:50 INFO - 'MAIL': '/var/mail/cltbld', 11:43:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:43:50 INFO - 'MOZ_NO_REMOTE': '1', 11:43:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:43:50 INFO - 'NO_EM_RESTART': '1', 11:43:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:43:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:43:50 INFO - 'PWD': '/builds/slave/test', 11:43:50 INFO - 'SHELL': '/bin/bash', 11:43:50 INFO - 'SHLVL': '1', 11:43:50 INFO - 'TERM': 'linux', 11:43:50 INFO - 'TMOUT': '86400', 11:43:50 INFO - 'USER': 'cltbld', 11:43:50 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1483212920.537035-964079285', 11:43:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:43:50 INFO - '_': '/tools/buildbot/bin/python'} 11:43:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:43:51 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:43:51 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:43:51 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:43:51 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:43:51 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:43:51 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:43:51 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:43:51 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:43:51 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:43:51 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:43:51 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:43:51 INFO - Cleaning up... 11:43:51 INFO - Return code: 0 11:43:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:43:51 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:43:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:43:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:43:51 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:43:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:43:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:43:51 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 0x20151f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2126a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2387480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2384820>, '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 0x2385520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23859b0>, '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': '41a7e32711b8e08598a63237000001d6-1483212920.537035-964079285', '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:43:51 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:43:51 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:43:51 INFO - Using env: {'DISPLAY': ':0', 11:43:51 INFO - 'HOME': '/home/cltbld', 11:43:51 INFO - 'LANG': 'en_US.UTF-8', 11:43:51 INFO - 'LANGUAGE': 'en_US:en', 11:43:51 INFO - 'LOGNAME': 'cltbld', 11:43:51 INFO - 'MAIL': '/var/mail/cltbld', 11:43:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:43:51 INFO - 'MOZ_NO_REMOTE': '1', 11:43:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:43:51 INFO - 'NO_EM_RESTART': '1', 11:43:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:43:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:43:51 INFO - 'PWD': '/builds/slave/test', 11:43:51 INFO - 'SHELL': '/bin/bash', 11:43:51 INFO - 'SHLVL': '1', 11:43:51 INFO - 'TERM': 'linux', 11:43:51 INFO - 'TMOUT': '86400', 11:43:51 INFO - 'USER': 'cltbld', 11:43:51 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1483212920.537035-964079285', 11:43:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:43:51 INFO - '_': '/tools/buildbot/bin/python'} 11:43:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:43:51 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:43:51 INFO - Cleaning up... 11:43:51 INFO - Return code: 0 11:43:51 INFO - Running post-action listener: _resource_record_post_action 11:43:51 INFO - Running post-action listener: _start_resource_monitoring 11:43:51 INFO - Starting resource monitoring. 11:43:51 INFO - [mozharness: 2016-12-31 19:43:51.323591Z] Finished create-virtualenv step (success) 11:43:51 INFO - [mozharness: 2016-12-31 19:43:51.324045Z] Running install step. 11:43:51 INFO - Running pre-action listener: _resource_record_pre_action 11:43:51 INFO - Running main action method: install 11:43:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:43:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:43:51 INFO - Reading from file tmpfile_stdout 11:43:51 INFO - Detecting whether we're running mozinstall >=1.0... 11:43:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:43:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:43:51 INFO - Reading from file tmpfile_stdout 11:43:51 INFO - Output received: 11:43:51 INFO - Usage: mozinstall [options] installer 11:43:51 INFO - Options: 11:43:51 INFO - -h, --help show this help message and exit 11:43:51 INFO - -d DEST, --destination=DEST 11:43:51 INFO - Directory to install application into. [default: 11:43:51 INFO - "/builds/slave/test"] 11:43:51 INFO - --app=APP Application being installed. [default: firefox] 11:43:51 INFO - mkdir: /builds/slave/test/build/application 11:43:51 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:43:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 11:44:07 INFO - Reading from file tmpfile_stdout 11:44:07 INFO - Output received: 11:44:07 INFO - /builds/slave/test/build/application/firefox/firefox 11:44:07 INFO - Running post-action listener: _resource_record_post_action 11:44:07 INFO - [mozharness: 2016-12-31 19:44:07.653343Z] Finished install step (success) 11:44:07 INFO - [mozharness: 2016-12-31 19:44:07.653619Z] Running run-tests step. 11:44:07 INFO - Running pre-action listener: _resource_record_pre_action 11:44:07 INFO - Running main action method: run_tests 11:44:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:44:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:44:07 INFO - Python 2.7.3 11:44:07 INFO - Return code: 0 11:44:07 INFO - grabbing minidump binary from tooltool 11:44:07 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:44:07 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 0x2384820>, '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 0x2385520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23859b0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:44:07 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:44:07 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:44:07 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:44:07 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:44:07 INFO - Return code: 0 11:44:07 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:44:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:44:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:44:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:44:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:44:07 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-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Q3tBUc7hTPO9FVhiFORHuQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-024', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 11:44:07 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-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Q3tBUc7hTPO9FVhiFORHuQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-024 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 11:44:07 INFO - Using env: {'DISPLAY': ':0', 11:44:07 INFO - 'HOME': '/home/cltbld', 11:44:07 INFO - 'LANG': 'en_US.UTF-8', 11:44:07 INFO - 'LANGUAGE': 'en_US:en', 11:44:07 INFO - 'LOGNAME': 'cltbld', 11:44:07 INFO - 'MAIL': '/var/mail/cltbld', 11:44:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:44:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:44:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:44:07 INFO - 'MOZ_NO_REMOTE': '1', 11:44:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:44:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:44:07 INFO - 'NO_EM_RESTART': '1', 11:44:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:44:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:44:07 INFO - 'PWD': '/builds/slave/test', 11:44:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:44:07 INFO - 'SHELL': '/bin/bash', 11:44:07 INFO - 'SHLVL': '1', 11:44:07 INFO - 'TERM': 'linux', 11:44:07 INFO - 'TMOUT': '86400', 11:44:07 INFO - 'USER': 'cltbld', 11:44:07 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1483212920.537035-964079285', 11:44:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:44:07 INFO - '_': '/tools/buildbot/bin/python'} 11:44:07 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-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Q3tBUc7hTPO9FVhiFORHuQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-024', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 11:44:07 INFO - mozversion application_buildid: 20161231190734 11:44:07 INFO - mozversion application_changeset: cb9f43eb552570e185e95dfc8acb9f9fbe47ae17 11:44:07 INFO - mozversion application_display_name: Nightly 11:44:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:44:07 INFO - mozversion application_name: Firefox 11:44:07 INFO - mozversion application_remotingname: firefox 11:44:07 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:44:07 INFO - mozversion application_vendor: Mozilla 11:44:07 INFO - mozversion application_version: 53.0a1 11:44:07 INFO - mozversion platform_buildid: 20161231190734 11:44:07 INFO - mozversion platform_changeset: cb9f43eb552570e185e95dfc8acb9f9fbe47ae17 11:44:07 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:44:07 INFO - mozversion platform_version: 53.0a1 11:44:07 INFO - using testdate: 1483213447 11:44:07 INFO - actual date: 1483213447 11:44:07 INFO - starting webserver on 'localhost:42044' 11:44:07 INFO - SUITE-START | Running 2 tests 11:44:07 INFO - TEST-START | dromaeo_css 11:44:07 INFO - operating with platform_type : linux_ 11:44:07 INFO - Initialising browser for dromaeo_css test... 11:44:07 INFO - TEST-INFO | started process 23846 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbURPAf/profile http://localhost:42044/getInfo.html) 11:44:08 INFO - PROCESS | 23846 | 1483213448469 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:44:12 INFO - PROCESS | 23846 | __metrics Screen width/height:1600/1200 11:44:12 INFO - PROCESS | 23846 | colorDepth:24 11:44:12 INFO - PROCESS | 23846 | Browser inner width/height: 1024/697 11:44:12 INFO - PROCESS | 23846 | __metrics 11:44:12 ERROR - PROCESS | 23846 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3256: TypeError: this.tabs is undefined 11:44:13 INFO - TEST-INFO | 23846: exit 0 11:44:13 INFO - Browser initialized. 11:44:13 INFO - Running cycle 1/1 for dromaeo_css test... 11:44:13 INFO - TEST-INFO | started process 23966 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbURPAf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:44:25 INFO - PROCESS | 23966 | RSS: Main: 157847552 11:44:25 INFO - PROCESS | 23966 | 11:45:15 INFO - PROCESS | 23966 | 1483213515582 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:47:29 INFO - PROCESS | 23966 | Cycle 1(1): loaded http://localhost:42044/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42044/tests/dromaeo/cssquery-ext.html) 11:47:31 INFO - PROCESS | 23966 | RSS: Main: 165675008 11:47:31 INFO - PROCESS | 23966 | 11:49:14 INFO - PROCESS | 23966 | ************************* 11:49:14 INFO - PROCESS | 23966 | A coding exception was thrown and uncaught in a Task. 11:49:14 INFO - PROCESS | 23966 | 11:49:14 INFO - PROCESS | 23966 | Full message: ReferenceError: fetch is not defined 11:49:14 INFO - PROCESS | 23966 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 11:49:14 INFO - PROCESS | 23966 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 11:49:14 INFO - PROCESS | 23966 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 11:49:14 INFO - PROCESS | 23966 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 11:49:14 INFO - PROCESS | 23966 | TaskImpl@resource://gre/modules/Task.jsm:277:3 11:49:14 INFO - PROCESS | 23966 | asyncFunction@resource://gre/modules/Task.jsm:252:14 11:49:14 INFO - PROCESS | 23966 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 11:49:14 INFO - PROCESS | 23966 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 11:49:14 INFO - PROCESS | 23966 | TaskImpl@resource://gre/modules/Task.jsm:277:3 11:49:14 INFO - PROCESS | 23966 | asyncFunction@resource://gre/modules/Task.jsm:252:14 11:49:14 INFO - PROCESS | 23966 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 11:49:14 INFO - PROCESS | 23966 | 11:49:14 INFO - PROCESS | 23966 | ************************* 11:50:34 INFO - PROCESS | 23966 | Cycle 1(1): loaded http://localhost:42044/tests/dromaeo/cssquery-ext.html (next: http://localhost:42044/tests/dromaeo/cssquery-jquery.html) 11:50:34 INFO - PROCESS | 23966 | RSS: Main: 162136064 11:50:34 INFO - PROCESS | 23966 | 11:53:36 INFO - PROCESS | 23966 | Cycle 1(1): loaded http://localhost:42044/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42044/tests/dromaeo/cssquery-mootools.html) 11:53:37 INFO - PROCESS | 23966 | RSS: Main: 167403520 11:53:37 INFO - PROCESS | 23966 | 11:56:39 INFO - PROCESS | 23966 | Cycle 1(1): loaded http://localhost:42044/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42044/tests/dromaeo/cssquery-prototype.html) 11:56:40 INFO - PROCESS | 23966 | RSS: Main: 162852864 11:56:40 INFO - PROCESS | 23966 | 11:59:43 INFO - PROCESS | 23966 | Cycle 1(1): loaded http://localhost:42044/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42044/tests/dromaeo/cssquery-yui.html) 11:59:43 INFO - PROCESS | 23966 | RSS: Main: 165560320 11:59:43 INFO - PROCESS | 23966 | 12:02:46 INFO - PROCESS | 23966 | Cycle 1(1): loaded http://localhost:42044/tests/dromaeo/cssquery-yui.html (next: http://localhost:42044/tests/dromaeo/cssquery-dojo.html) 12:02:46 INFO - PROCESS | 23966 | RSS: Main: 166277120 12:02:46 INFO - PROCESS | 23966 | 12:02:46 INFO - PROCESS | 23966 | __start_tp_report 12:02:46 INFO - PROCESS | 23966 | _x_x_mozilla_page_load 12:02:46 INFO - PROCESS | 23966 | _x_x_mozilla_page_load_details 12:02:46 INFO - PROCESS | 23966 | |i|pagename|runs| 12:02:46 INFO - PROCESS | 23966 | |0;dojo.html;2586.965213914434;2616;2617.437250991037;2621.5412302847;2658.5746280595095;4817.181079216532;4857.757112144387;4875.098106850235;4885.828995985137;4901.852944411669;4833.419989601244;4846.539578740023;4887.975560122203;4888.462269150391;4895.290182923474;4455.576720211575;4460.79926403311;4554.294084416921;4570.13167498176;4622.39908811854;4500.97749511252;4511.729296242236;4552.658550608701;4559.088182363524;4597.494275629678;4409.4488188976375;4424.646028317727;4444.799984000728;4527.275635898273;4555.430571178603;4420.778961051935;4476.798544065525;4481.663875209372;4513.50351461339;4541.069080838421;4454.064646424223;4456.08650226706;4456.19788438081;4471.709338892962;4489.012417268195;4692.741899195543;4705.035467729107;4840.15297322967;4846.709197448165;4854.199057155565;5121.590272778169;5155.53600175986;5159.329287192641;5159.71621560814;5161.922571161435;5177.275181474596;5185.766640501149;5192.506711862367;5193.454687257821;5200.843974680728;4825.348577941968;4827.782749776268;4830.3479030331155;4836.202026665631;4862.5380588844;5207.973960130175;5247.895042099137;5266.552343050884;5267.947320526822;5275.419703832652;5055.241713742968;5065.062963351718;5079.390473143247;5080.212567052113;5101.617378696613;4995.0509403214;5160.819371321985;5178.197379406198;5230.032443997872;5301.125314323171;5127.846164615067;5130.87172820675;5142.30578871855;5183.637145399785;5231.764570594332;5067.594592432597;5196.584273258206;5199.4020687621405;5223.268742376071;5259.0796610593;4339.587739164775;4343.391925130484;4349.913001740011;4370.104128653618;4439.511653718088;3771;3771.8114094295174;3778.6599206071587;3809.3714537101623;3811.104390468238;4451.77741112943;4478.440194975628;4486.551344865553;4497.955020449819;4531.660125490602;5144.9742751286;5184.792608295701;5207.3490813648295;5222.2427747976335;5237.816676416307;4495.370648109268;4505.05393867285;4525.570070843265;4532.410786597721;4550.385697930737;4676.3453116563705;4676.438827340741;4676.672632915731;4700.95299047012;4719.669623126349;4736.526347365236;4742.739149346681;4796.424429068534;4797.808087676453;4831;5255.159174532057;5262.158054711228;5264.368275806928;5309.787608495617;5326.573874089986;4811.133995880775;4830.541098595628;4841.709497430165;4846.781894814671;4860.927086093782;5016.2224855147515;5083.745812709276;5085.8728531787;5185.092608793521;5194.246834208943;5169.586433085269;5215.817446389358;5219.660722053054;5226.477352264743;5230.712310822942;5006.07387633289;5040.344755181803;5040.571551418024;5045.899082018265;5055.140626093499;4990.700557966534;5004.1492946198505;5006.324146240211;5074.137396642654;5100.719460429712;4963.205887058198;4972.726500042532;4974.353334066693;5009.0482808266315;5020.42265139499;5084.669496482717;5088.84733457982;5091.439941606494;5099.2860999460845;5106.183010718416;4822.879428014187;4844.152273352079;4880.316755654282;4945.035718035093;4948.05986862495 12:02:46 INFO - PROCESS | 23966 | |1;ext.html;507.8831868670206;517.7488917874832;518.9922151167731;526.8946210757847;527.9102552566063;37142.814285928565;38949.61050389495;38956.61043389565;41929.79035104828;42121.78939105308;38580.80709596448;38823.41764873529;38831.61168388315;38915.416268755995;38993.41509877354;7602.2397760224085;7777.144514103444;7791.766247012599;9457.10157535033;9811.264155188353;9682.709518714415;9780.510974451285;9785.363951343175;9789.755256118618;9792.069753373418;9752.90247097527;9781.070798274188;9808.871979722351;9821.312508124434;9823.263255255848;9686.854697179513;9725.513724313827;9735.415875047483;9736.464494452803;9755.219582433388;9451.243900488013;9518.476483793389;9523.666671666459;9528.857067143997;9528.95235523818;33200.66799332;33243.50134747981;33437.498437523456;33586.496202556984;33609.49585756216;3549.929001419983;3638.1450359165574;3647.1429214134655;3657.122290650225;3691.3171063353097;25066.624000640004;25110.372240693945;25302.74697253022;25521.48957020849;26544.8672756635;3336.6830151135605;3446.6553344665585;3542.6634469725336;3574.1064733816547;3580.820958952042;238845;240443;240938.7953060241;240981;242654;45852.541474585494;46389.7680511602;46401;46401.76799115983;46408.303875441896;47561.524384756405;47636;47830.5216947833;47882.28176577354;47882.521174788504;11884.286942783288;11934.761304774029;11945.940270298594;12002.819957700642;12008.219465734699;8294.294984926228;8449.873251901226;9752.073553012453;9948.950255248677;10066.09405153525;14942.551723448314;15125.319360628799;15146.848531514765;15294;15316.770248446282;6222.62664240147;6268;6323.683815809283;6324.683765811691;6592.406683398517;744.9441291903021;745.1244787374812;766.1878408886598;776.9899131129622;781.1485480825927;27116.457670846474;27694.3076423087;27982.720172798417;28042.43915121727;28405.857970710014;919.0992827029415;980.3921568627354;981.3719219699398;993.3394292795355;996.7059717383389;13940.721185576434;14793.482228122179;14981.101133932;14989.70020599582;15043.774343384857;366666;369104;369290;370224;371211;49148.75425622849;49207.26189107166;49276.50723492791;49319;49740.75129624401;1170.6897672116895;1180.9055275577932;1210.4673943464848;1218.0925210717894;1224.3449754381363;25305.746942531074;25871;27071.86464067667;27088;27186.72813271842;40572.1885562293;41063.38404923869;41099.383509246785;41237;41308;297415;298069;299453;299492;300372;22274;22780.633162010334;23363.299101026998;23970.52058958846;24577.385565360295;39811.80094099511;39903.20193596054;40067;40181.397279040255;40348;34255.82872085624;34762.478562822085;34771;34865.30269394648;35068;34349.656503435646;34764.13089672779;34963.82518087393;35332;35366.823165885035;33265;33423;33785.83107084449;34022.14944626404;34343.141421464665;28816.423671526034;31110;31112;31266.843665781525;31342.37315253728;23217.303480894923;23277.18529851515;23564.347526133475;23676;23940.88029559841 12:02:46 INFO - PROCESS | 23966 | |2;jquery.html;366.28208710926583;371.89586915663614;372.5808465476338;379.32669511616876;389.8635477582845;15133.697326053487;15216.391344346226;16553.83446165541;16553.999999999985;16637.084960327178;16118.11350375729;16404.75392869108;16419.261133249;16569.088700121487;16588.08765517899;4471.217536931033;4737.763111844394;4766.022965292124;4766.809327626908;4777.856664300077;4672.509386514418;4701.670883038189;4711.128441238365;4712.151812673717;4732.810687572493;4636.258198688193;5052.317937078503;5089.083964886319;5096.337476128117;5100.183970564711;5042.82350117746;5073.39119305682;5078.263651770472;5082.745862706868;5084.949150508503;4413.889652758675;4652.441706995182;4693.155232058228;4693.78877950493;4695.765211739399;12187.817182742267;12282.631521054383;12365;12384.504619815196;12409.937950310281;2074.844386670991;2121.8832964186963;2136.8290536757177;2164.2641501889275;2175.59751445983;8730.17063378978;9461.101195386427;9717.222622190278;12363.752724945542;12427.943624791904;1738.2699266308182;1743.7297218931085;1744.5464179313342;1754.4911975527082;1798.8830726002768;61986;64744.67627661832;65814.3418565813;65889.34110658927;67118.66440667814;9137.862932056025;9398.20115290208;9412.858807117898;9471;9588.089131532493;12804.039697022761;12836.935815320863;12884.742305154032;12885.355732213351;12899.90350820172;6664.566803157779;6787.389134977876;6823.522353435311;6938.722451102;6964.408025317804;6359.982402815527;6386.584871983307;6544.345565443417;6598.406143447113;6615.106960560359;3790.5261842269715;3890.727649064595;3967.7222594418113;4065.308897487434;4081.8775436736946;1841.8526517878538;1858.8605854560692;1858.9070546472622;1872.2885303584824;1895.0808857704003;810.8662070758259;839.2656425627575;841.5413599588239;858.3390789092364;877.368294827723;15355.474676953967;15795.131267780382;16030.839691602934;16119.758203626954;16737.38331752618;829.3613917283658;830.2071521696765;835.543502103825;838.773531146587;862.2196911794836;8979.865302020475;9073.863892041625;9127.406718563272;9297.349185557081;9401.491650056709;251306;256095;341584;347162;347785;74213.32080112815;75448.24551754522;76029;77311;77473;918.4581097152712;965.1603105298435;966.3235734985678;980.5832521178471;981.40134517944;15141.318640660977;15183;15326.693466130839;15642.843571564139;15952;33870.66129338675;34701.091439971045;35071.47392789059;35770.64229357673;36309.27381452303;46933.530664694284;47804;47857.76071119762;47858;47901.28148077712;7479.700811967678;7531.246875312425;7648.273414025881;7750.379969602531;7787.766367008999;11367.48846301935;11861.347625880659;12058.397080146133;12062.336571488302;12100.334481603244;4791.976040119777;5126.641135120506;9228;9323.766905827408;9791.314607977372;9063.229625481908;10265.640702575673;10284.948575257076;10344.53449594755;10481.056704896597;5708.457696518824;7846.60766961661;8858.468491890508;10339.948300258451;10581.36511809294;7694.1536430993665;7815.53106813593;7901.288884000468;7964.920350796418;8000.39997000239;7746.56688512627;9138;10048.207066049199;10086.697399078039;10388.065074143364 12:02:46 INFO - PROCESS | 23966 | |3;mootools.html;1286.5625687266333;1310.842698876135;1312.8949684025276;1320.0693511074687;1323.6426164935467;6118.816435506931;6254.874902501959;6957.965210173948;7440.851182976344;7450.590217538033;6639.336066393358;6863.107795986515;6869.55347902386;7027.402670772991;7037.331453511921;2074.8962551872382;2135.263334149716;2139.839512036596;2141.7751136130714;2142.5607750411127;2091.236698605006;2115.809577138057;2166.880821554814;2171.348595421375;2179.9128034878586;2103.5897999890026;2118.4597927528457;2133.232036466871;2146.774588668191;2146.8819214943173;2078.7193728846564;2079.1683326669377;2088.979110208901;2090.2057218256964;2099.8740075595515;2062.597793430282;2079.740032495938;2080.6566916458764;2091.205341970057;2102.4743814046487;7357.301056399633;7563.508371955805;7602.657880395395;7644.732434364771;7655.578943158124;4260.8721738347895;4387.451568553931;4390.868273951786;4391.582799634061;4395.758233297167;2719.5648696208514;2895.348546577003;2932.7653787697145;2938.6620538637903;2959.4377068356944;1150.6317978246964;1175.2184797109912;1184.538030168235;1212.121212121205;1218.8720184380602;9843.409395436296;9911.355761875528;9934.25493088014;9969.052939970692;9975.451350175737;3770.792606406674;3930.390789427643;3960.9009774755864;4038.818253178614;4057.3102572562734;7945.364370850318;7971.481853679492;7990.200979901963;8002.03975522941;8019.278264956182;4425.668074894395;4451.933221001687;4498.977505112519;4514.571115743999;4556.7721613919175;4010.8796736097966;4121.5466298707115;4193.22425351311;4194.035371864427;4200.936985945214;3222.323312104484;3271.8364081795817;3335.282914173464;3337.7830441021256;3357.3956687795944;942.0861764088822;983.3608154699359;985.113397941858;987.2792861211356;993.2419617277523;787.118427361359;811.1280373598405;814.4502460839028;818.2758258940761;825.8430898129336;4665.300204969281;4813.133635945564;5005.74971251436;5013.774380152902;5126.538936403107;801.0427539090731;849.7578190215759;854.3933806996978;892.2817132208505;893.9374243802871;3239.433099207676;3408.897733068012;3478.252175782219;3580.212353282274;3661.56061272649;8840.204381605687;8927.24118449939;9050.86423703645;9077.137671921157;9116.954415228014;8941.508217048126;8999.550022498848;9025.954870225607;9042.412243204171;9093.81812363749;6815.795526134125;7045.436365090781;7066.540674856118;7080.291970802981;7086.645667716593;4746.857594272177;4975.676581022222;5003.749812509433;5061.620378471555;5365.9463405367005;8156.796080097808;8168.877466838121;8221.79445513867;8237.341012719087;8249.257566819015;8231.753047408347;8294.543800091053;8304.460210086325;8319.376046796635;8452.36607254446;6454.5159113065765;6626.734930602722;6631.602103873783;6637.435817955335;6642.169728783902;6784.151981002375;6941.062956500808;7031.859362812817;7237;7239.746608868664;6069.453749162597;6212.130301757667;6272.247330320391;6404.327545607831;6425.228972523327;6395.648239346694;6414.03789431589;6424.261209960914;6443.259025212161;6452.096706461193;5613.943860561342;5704.714764261853;5715.685637289989;5725.370209277059;5729.140628905698;4178.435911151955;4214.115035842385;4214.536400995949;4294.677899157638;4326;4275.29457639486;4334.28484300087;4350;4454.688171827941;4551.886202844956 12:02:46 INFO - PROCESS | 23966 | |4;prototype.html;340.95397121388623;350.62658268943574;358.45156909927806;366.0354964668099;367.13173345038973;4713.575778179963;4909.656324057318;4952.207646776516;4977.427595826481;4984.327115839361;4798.592119669832;4872.293517439969;4966.826161084363;4968.726720030397;4970.179920313153;3996.220736956295;4021.718479706422;4040.151568170688;4040.9797951010205;4044;3933.744306620075;3988.6239247459725;3990.8603198888045;4031.778252196128;4042.1915616876595;3941.7437866538585;3955.545112312078;3960;3961.9603803962023;3983.243183795084;3938.9606103939022;3944.2505923874514;3953.9406908896103;3962.1085255817497;3969.6625786808154;3573.821308934569;3577.3918433866047;3588.4258518637153;3605.188832512664;3608.639136086397;4673.532646735299;4821.252705830602;4880.8047678092835;4893.0947774661445;4921.163402221595;4501.549845015505;4505.909881802379;4544.363789069533;4546.20441422753;4577.793999270008;5202.817901373433;5208.895822083575;5210.583153347723;5212.29633999409;5226.764795584208;3726.4782930389765;3886.650201481852;3898.3567711327596;3929.7445666031904;3937.3897045957638;5397.8380648580605;5410.431904649994;5412.621116521845;5415.268938693304;5438.374586922515;4000.19996000801;4003.2994226010333;4016.196760647882;4024.2957482440456;4031.6774658027875;3961.069148750041;4092.7748973806133;4094.5700701426517;4105.4252404663375;4106.638682757367;4477.507474177838;4498.257787465097;4502.212112880298;4510.954890451054;4523.163214805271;5185.611079169077;5191.844244672666;5202.635815492955;5204.21936709489;5243.790248390022;1299.466629377325;1317.6508225320297;1360.6530334764573;1374.415873253863;1390.8887289017057;977.5942983661843;998.3810037776625;1005.5073014222978;1006.8137394581879;1016.3038318751679;4075;4294.592013758688;4342.652587792969;4353.238183317907;4381.583749543852;5100.285959965608;5101.642884998015;5110;5118.539331460112;5129.102407078821;4364.956350436519;4372.519022907478;4383.238559707428;4389.582989615981;4389.670774691911;2652.7082020977678;2717.945641087167;2728.871480245412;2732.6720793504905;2743.4238809849758;4717.599004084623;4735.976320118378;4737.407824021997;4738.834140805125;4751.976240118777;5061.4179369373;5228.039485193052;5257.185136303879;5267.920981185285;5267.9736601316745;4692.577668009895;4715.27892319301;4735.550122738335;4745.962583946208;4749.430068391746;5128.486986414318;5132.820351287687;5152.613553983391;5194.35070616173;5201.661891977009;4822.493638168082;5089.949100509096;5115.309433226466;5115.693058416507;5125.513601055801;4521.389612402284;4632.814687412466;4641.582257596832;4647.883802904955;4663.11143986686;3883.8446462141196;3886.5724770274683;3893.1824316892635;3934.488516492952;3934.586868378779;5122.847359344118;5137.717425541481;5156.510131537498;5157.200633901752;5168.896622067613;4961.305417241661;5176.792928282826;5182.922256166236;5199.636025478331;5209.166533354645;5181.81863634771;5200.583953283804;5208.713520756395;5220.608454365862;5238.266642670105;4886.65793394459;5121.411037730709;5130.307408499804;5140.177571588528;5153.582764739726;5152.510511501401;5197.376314842092;5201.349831271091;5204.2974198484235;5211.60912931524;5053.343065401621;5061.645684802029;5074.188129899198;5075.680323115943;5077.090640207094 12:02:46 INFO - PROCESS | 23966 | |5;yui.html;677;679.6635665345655;732.1251104929607;744.6723441685658;756.9490198798194;12487.625371238855;12900.935495322521;13901.374438150282;14094.64763380916;14202.857971420295;13645.658858528543;14105.435782568686;14108.365123569438;14878.55364339069;14900.329485173166;780.7345502529167;803.3653413803117;804.6620419423841;827.8178800663844;828.3207769628907;786.4927122006302;794.003525575402;794.1264608930188;794.3406972213078;794.9880751788698;853.6158728572136;860.1269711243093;860.4321148042293;861.1345597674328;861.29804209569;849.6176720475811;853.0445900591316;853.3983541603155;854.0690647194588;854.2781349759443;624.7282432142033;628.6730899932055;628.789355565886;628.8993760998264;629.4586655476287;14046;14314.713705725932;14335.713285734228;14410.351534180987;14492.782608260884;12818.423170957329;13231.603051908458;13416.262105584188;13444.663883402894;13545.187288762705;10272.589096436133;10277.17782577392;10310.948445257725;10523;10688.19838512107;3186.1715953851935;3209;3232.9676703233135;3235.4176248275303;3276.6231883333485;62684.37315626831;62927.6853615729;63300.36699632991;63410.04884926684;65572;9847.409155450696;9894.208463322917;10037.347572407916;10108.191344692406;10156.23828212887;18202.45392638223;18223.17995690197;18226.45320640383;18283.451496455127;18362.173702183434;6335.194469576149;6352.428281454691;6357.523185761086;6486.4486518646445;6562.409383155539;3598.442241452579;3611.8013509257244;3644.6173151818675;3651.653092956165;3732.757370770891;2783.6798768141516;2837.2481292457514;2843.9857800710865;2860.413615208877;2872.066578361999;466.20512026993896;467.04023232257356;469.3194867442154;474.11814025911775;477.51293680446446;615.7383112177307;636.5989426661174;645.8245992294073;647.5920170292685;651.6741629185407;7883.842323153504;8512.489250644981;8792.296121575055;8817.911820881709;8971.50656713874;483.8887055977145;489.84814707440097;493.62977766674993;577.9423654711867;661.8080756580538;3648.492342054865;3919.862804801818;4006.759594424344;4012.7592344459426;4105.527864295585;62358.688206559586;62419;63014.36985630085;65641;65993.34006659871;54429.72785136049;55364;56665;56847.14729279064;58717;48555.757221213666;48810;49149.26276105861;49941.75029124758;50438.49561504338;6939.201991770809;7207.279272072751;7232.204457509775;7343.412526997934;7351.264873512606;33860.661393386734;33867;34072.82963585166;34815.56291059602;35283;42896.571034289256;42899.35650965301;43707.562924371625;45700.771496142304;45965.31052034289;9735.653138871287;9925.801483970425;9954.950225248827;10079.395236285847;10155.39067655943;11108.277961932448;11219.67252300696;11222.607208747655;11319.264247823865;11427.657170284905;10971.451427428437;11664.130914208983;12286.262824230575;12316.630501084981;13303.201807891557;12149.69625759363;12333.75332493363;12621.242725436503;13135.540256090992;13224.338783061;11328.207025508136;11785.528578856749;11851.348175850066;12217.328046957502;12546.184498007966;10565.600347723424;10647.94676026615;10914.072303854262;11061.61284355076;11223.719407014563;9911.603535858485;10450.529726162189;10653.9467302663;10828.945855270673;10885 12:02:46 INFO - PROCESS | 23966 | __end_tp_report 12:02:46 INFO - PROCESS | 23966 | __start_cc_report 12:02:46 INFO - PROCESS | 23966 | _x_x_mozilla_cycle_collect,322 12:02:46 INFO - PROCESS | 23966 | __end_cc_report 12:02:46 INFO - PROCESS | 23966 | __startTimestamp1483214566880__endTimestamp 12:02:46 INFO - PROCESS | 23966 | 12:02:46 INFO - PROCESS | 23966 | ------- Summary: start ------- 12:02:46 INFO - PROCESS | 23966 | Number of tests: 6 12:02:46 INFO - PROCESS | 23966 | 12:02:46 INFO - PROCESS | 23966 | [#0] dojo.html Cycles:165 Average:4788.15 Median:4880.32 stddev:510.41 (10.5%) stddev-sans-first:481.89 12:02:46 INFO - PROCESS | 23966 | Values: 2587.0 2616.0 2617.4 2621.5 2658.6 4817.2 4857.8 4875.1 4885.8 4901.9 4833.4 4846.5 4888.0 4888.5 4895.3 4455.6 4460.8 4554.3 4570.1 4622.4 4501.0 4511.7 4552.7 4559.1 4597.5 4409.4 4424.6 4444.8 4527.3 4555.4 4420.8 4476.8 4481.7 4513.5 4541.1 4454.1 4456.1 4456.2 4471.7 4489.0 4692.7 4705.0 4840.2 4846.7 4854.2 5121.6 5155.5 5159.3 5159.7 5161.9 5177.3 5185.8 5192.5 5193.5 5200.8 4825.3 4827.8 4830.3 4836.2 4862.5 5208.0 5247.9 5266.6 5267.9 5275.4 5055.2 5065.1 5079.4 5080.2 5101.6 4995.1 5160.8 5178.2 5230.0 5301.1 5127.8 5130.9 5142.3 5183.6 5231.8 5067.6 5196.6 5199.4 5223.3 5259.1 4339.6 4343.4 4349.9 4370.1 4439.5 3771.0 3771.8 3778.7 3809.4 3811.1 4451.8 4478.4 4486.6 4498.0 4531.7 5145.0 5184.8 5207.3 5222.2 5237.8 4495.4 4505.1 4525.6 4532.4 4550.4 4676.3 4676.4 4676.7 4701.0 4719.7 4736.5 4742.7 4796.4 4797.8 4831.0 5255.2 5262.2 5264.4 5309.8 5326.6 4811.1 4830.5 4841.7 4846.8 4860.9 5016.2 5083.7 5085.9 5185.1 5194.2 5169.6 5215.8 5219.7 5226.5 5230.7 5006.1 5040.3 5040.6 5045.9 5055.1 4990.7 5004.1 5006.3 5074.1 5100.7 4963.2 4972.7 4974.4 5009.0 5020.4 5084.7 5088.8 5091.4 5099.3 5106.2 4822.9 4844.2 4880.3 4945.0 4948.1 12:02:46 INFO - PROCESS | 23966 | 12:02:46 INFO - PROCESS | 23966 | [#1] ext.html Cycles:180 Average:45055.24 Median:185178.66 stddev:80840.79 (43.7%) stddev-sans-first:80998.37 12:02:46 INFO - PROCESS | 23966 | Values: 507.9 517.7 519.0 526.9 527.9 37142.8 38949.6 38956.6 41929.8 42121.8 38580.8 38823.4 38831.6 38915.4 38993.4 7602.2 7777.1 7791.8 9457.1 9811.3 9682.7 9780.5 9785.4 9789.8 9792.1 9752.9 9781.1 9808.9 9821.3 9823.3 9686.9 9725.5 9735.4 9736.5 9755.2 9451.2 9518.5 9523.7 9528.9 9529.0 33200.7 33243.5 33437.5 33586.5 33609.5 3549.9 3638.1 3647.1 3657.1 3691.3 25066.6 25110.4 25302.7 25521.5 26544.9 3336.7 3446.7 3542.7 3574.1 3580.8 238845.0 240443.0 240938.8 240981.0 242654.0 45852.5 46389.8 46401.0 46401.8 46408.3 47561.5 47636.0 47830.5 47882.3 47882.5 11884.3 11934.8 11945.9 12002.8 12008.2 8294.3 8449.9 9752.1 9949.0 10066.1 14942.6 15125.3 15146.8 15294.0 15316.8 6222.6 6268.0 6323.7 6324.7 6592.4 744.9 745.1 766.2 777.0 781.1 27116.5 27694.3 27982.7 28042.4 28405.9 919.1 980.4 981.4 993.3 996.7 13940.7 14793.5 14981.1 14989.7 15043.8 366666.0 369104.0 369290.0 370224.0 371211.0 49148.8 49207.3 49276.5 49319.0 49740.8 1170.7 1180.9 1210.5 1218.1 1224.3 25305.7 25871.0 27071.9 27088.0 27186.7 40572.2 41063.4 41099.4 41237.0 41308.0 297415.0 298069.0 299453.0 299492.0 300372.0 22274.0 22780.6 23363.3 23970.5 24577.4 39811.8 39903.2 40067.0 40181.4 40348.0 34255.8 34762.5 34771.0 34865.3 35068.0 34349.7 34764.1 34963.8 35332.0 35366.8 33265.0 33423.0 33785.8 34022.1 34343.1 28816.4 31110.0 31112.0 31266.8 31342.4 23217.3 23277.2 23564.3 23676.0 23940.9 12:02:46 INFO - PROCESS | 23966 | 12:02:46 INFO - PROCESS | 23966 | [#2] jquery.html Cycles:180 Average:21235.68 Median:4706.40 stddev:52091.48 (1106.8%) stddev-sans-first:52214.04 12:02:46 INFO - PROCESS | 23966 | Values: 366.3 371.9 372.6 379.3 389.9 15133.7 15216.4 16553.8 16554.0 16637.1 16118.1 16404.8 16419.3 16569.1 16588.1 4471.2 4737.8 4766.0 4766.8 4777.9 4672.5 4701.7 4711.1 4712.2 4732.8 4636.3 5052.3 5089.1 5096.3 5100.2 5042.8 5073.4 5078.3 5082.7 5084.9 4413.9 4652.4 4693.2 4693.8 4695.8 12187.8 12282.6 12365.0 12384.5 12409.9 2074.8 2121.9 2136.8 2164.3 2175.6 8730.2 9461.1 9717.2 12363.8 12427.9 1738.3 1743.7 1744.5 1754.5 1798.9 61986.0 64744.7 65814.3 65889.3 67118.7 9137.9 9398.2 9412.9 9471.0 9588.1 12804.0 12836.9 12884.7 12885.4 12899.9 6664.6 6787.4 6823.5 6938.7 6964.4 6360.0 6386.6 6544.3 6598.4 6615.1 3790.5 3890.7 3967.7 4065.3 4081.9 1841.9 1858.9 1858.9 1872.3 1895.1 810.9 839.3 841.5 858.3 877.4 15355.5 15795.1 16030.8 16119.8 16737.4 829.4 830.2 835.5 838.8 862.2 8979.9 9073.9 9127.4 9297.3 9401.5 251306.0 256095.0 341584.0 347162.0 347785.0 74213.3 75448.2 76029.0 77311.0 77473.0 918.5 965.2 966.3 980.6 981.4 15141.3 15183.0 15326.7 15642.8 15952.0 33870.7 34701.1 35071.5 35770.6 36309.3 46933.5 47804.0 47857.8 47858.0 47901.3 7479.7 7531.2 7648.3 7750.4 7787.8 11367.5 11861.3 12058.4 12062.3 12100.3 4792.0 5126.6 9228.0 9323.8 9791.3 9063.2 10265.6 10284.9 10344.5 10481.1 5708.5 7846.6 8858.5 10339.9 10581.4 7694.2 7815.5 7901.3 7964.9 8000.4 7746.6 9138.0 10048.2 10086.7 10388.1 12:02:46 INFO - PROCESS | 23966 | 12:02:46 INFO - PROCESS | 23966 | [#3] mootools.html Cycles:180 Average:4826.56 Median:5659.33 stddev:2634.30 (46.5%) stddev-sans-first:2628.25 12:02:46 INFO - PROCESS | 23966 | Values: 1286.6 1310.8 1312.9 1320.1 1323.6 6118.8 6254.9 6958.0 7440.9 7450.6 6639.3 6863.1 6869.6 7027.4 7037.3 2074.9 2135.3 2139.8 2141.8 2142.6 2091.2 2115.8 2166.9 2171.3 2179.9 2103.6 2118.5 2133.2 2146.8 2146.9 2078.7 2079.2 2089.0 2090.2 2099.9 2062.6 2079.7 2080.7 2091.2 2102.5 7357.3 7563.5 7602.7 7644.7 7655.6 4260.9 4387.5 4390.9 4391.6 4395.8 2719.6 2895.3 2932.8 2938.7 2959.4 1150.6 1175.2 1184.5 1212.1 1218.9 9843.4 9911.4 9934.3 9969.1 9975.5 3770.8 3930.4 3960.9 4038.8 4057.3 7945.4 7971.5 7990.2 8002.0 8019.3 4425.7 4451.9 4499.0 4514.6 4556.8 4010.9 4121.5 4193.2 4194.0 4200.9 3222.3 3271.8 3335.3 3337.8 3357.4 942.1 983.4 985.1 987.3 993.2 787.1 811.1 814.5 818.3 825.8 4665.3 4813.1 5005.7 5013.8 5126.5 801.0 849.8 854.4 892.3 893.9 3239.4 3408.9 3478.3 3580.2 3661.6 8840.2 8927.2 9050.9 9077.1 9117.0 8941.5 8999.6 9026.0 9042.4 9093.8 6815.8 7045.4 7066.5 7080.3 7086.6 4746.9 4975.7 5003.7 5061.6 5365.9 8156.8 8168.9 8221.8 8237.3 8249.3 8231.8 8294.5 8304.5 8319.4 8452.4 6454.5 6626.7 6631.6 6637.4 6642.2 6784.2 6941.1 7031.9 7237.0 7239.7 6069.5 6212.1 6272.2 6404.3 6425.2 6395.6 6414.0 6424.3 6443.3 6452.1 5613.9 5704.7 5715.7 5725.4 5729.1 4178.4 4214.1 4214.5 4294.7 4326.0 4275.3 4334.3 4350.0 4454.7 4551.9 12:02:46 INFO - PROCESS | 23966 | 12:02:46 INFO - PROCESS | 23966 | [#4] prototype.html Cycles:180 Average:4291.80 Median:4714.43 stddev:1190.21 (25.2%) stddev-sans-first:1156.01 12:02:46 INFO - PROCESS | 23966 | Values: 341.0 350.6 358.5 366.0 367.1 4713.6 4909.7 4952.2 4977.4 4984.3 4798.6 4872.3 4966.8 4968.7 4970.2 3996.2 4021.7 4040.2 4041.0 4044.0 3933.7 3988.6 3990.9 4031.8 4042.2 3941.7 3955.5 3960.0 3962.0 3983.2 3939.0 3944.3 3953.9 3962.1 3969.7 3573.8 3577.4 3588.4 3605.2 3608.6 4673.5 4821.3 4880.8 4893.1 4921.2 4501.5 4505.9 4544.4 4546.2 4577.8 5202.8 5208.9 5210.6 5212.3 5226.8 3726.5 3886.7 3898.4 3929.7 3937.4 5397.8 5410.4 5412.6 5415.3 5438.4 4000.2 4003.3 4016.2 4024.3 4031.7 3961.1 4092.8 4094.6 4105.4 4106.6 4477.5 4498.3 4502.2 4511.0 4523.2 5185.6 5191.8 5202.6 5204.2 5243.8 1299.5 1317.7 1360.7 1374.4 1390.9 977.6 998.4 1005.5 1006.8 1016.3 4075.0 4294.6 4342.7 4353.2 4381.6 5100.3 5101.6 5110.0 5118.5 5129.1 4365.0 4372.5 4383.2 4389.6 4389.7 2652.7 2717.9 2728.9 2732.7 2743.4 4717.6 4736.0 4737.4 4738.8 4752.0 5061.4 5228.0 5257.2 5267.9 5268.0 4692.6 4715.3 4735.6 4746.0 4749.4 5128.5 5132.8 5152.6 5194.4 5201.7 4822.5 5089.9 5115.3 5115.7 5125.5 4521.4 4632.8 4641.6 4647.9 4663.1 3883.8 3886.6 3893.2 3934.5 3934.6 5122.8 5137.7 5156.5 5157.2 5168.9 4961.3 5176.8 5182.9 5199.6 5209.2 5181.8 5200.6 5208.7 5220.6 5238.3 4886.7 5121.4 5130.3 5140.2 5153.6 5152.5 5197.4 5201.3 5204.3 5211.6 5053.3 5061.6 5074.2 5075.7 5077.1 12:02:46 INFO - PROCESS | 23966 | 12:02:46 INFO - PROCESS | 23966 | [#5] yui.html Cycles:180 Average:14655.58 Median:43303.46 stddev:17998.54 (41.6%) stddev-sans-first:18018.42 12:02:46 INFO - PROCESS | 23966 | Values: 677.0 679.7 732.1 744.7 756.9 12487.6 12900.9 13901.4 14094.6 14202.9 13645.7 14105.4 14108.4 14878.6 14900.3 780.7 803.4 804.7 827.8 828.3 786.5 794.0 794.1 794.3 795.0 853.6 860.1 860.4 861.1 861.3 849.6 853.0 853.4 854.1 854.3 624.7 628.7 628.8 628.9 629.5 14046.0 14314.7 14335.7 14410.4 14492.8 12818.4 13231.6 13416.3 13444.7 13545.2 10272.6 10277.2 10310.9 10523.0 10688.2 3186.2 3209.0 3233.0 3235.4 3276.6 62684.4 62927.7 63300.4 63410.0 65572.0 9847.4 9894.2 10037.3 10108.2 10156.2 18202.5 18223.2 18226.5 18283.5 18362.2 6335.2 6352.4 6357.5 6486.4 6562.4 3598.4 3611.8 3644.6 3651.7 3732.8 2783.7 2837.2 2844.0 2860.4 2872.1 466.2 467.0 469.3 474.1 477.5 615.7 636.6 645.8 647.6 651.7 7883.8 8512.5 8792.3 8817.9 8971.5 483.9 489.8 493.6 577.9 661.8 3648.5 3919.9 4006.8 4012.8 4105.5 62358.7 62419.0 63014.4 65641.0 65993.3 54429.7 55364.0 56665.0 56847.1 58717.0 48555.8 48810.0 49149.3 49941.8 50438.5 6939.2 7207.3 7232.2 7343.4 7351.3 33860.7 33867.0 34072.8 34815.6 35283.0 42896.6 42899.4 43707.6 45700.8 45965.3 9735.7 9925.8 9955.0 10079.4 10155.4 11108.3 11219.7 11222.6 11319.3 11427.7 10971.5 11664.1 12286.3 12316.6 13303.2 12149.7 12333.8 12621.2 13135.5 13224.3 11328.2 11785.5 11851.3 12217.3 12546.2 10565.6 10647.9 10914.1 11061.6 11223.7 9911.6 10450.5 10653.9 10828.9 10885.0 12:02:46 INFO - PROCESS | 23966 | -------- Summary: end -------- 12:02:46 INFO - PROCESS | 23966 | 12:02:47 INFO - TEST-INFO | 23966: exit 0 12:02:47 INFO - TEST-OK | dromaeo_css | took 1119798ms 12:02:47 INFO - TEST-START | kraken 12:02:47 INFO - operating with platform_type : linux_ 12:02:47 INFO - Initialising browser for kraken test... 12:02:47 INFO - TEST-INFO | started process 24320 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2L7PKX/profile http://localhost:42044/getInfo.html) 12:02:48 INFO - PROCESS | 24320 | 1483214568225 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:02:52 INFO - PROCESS | 24320 | __metrics Screen width/height:1600/1200 12:02:52 INFO - PROCESS | 24320 | colorDepth:24 12:02:52 INFO - PROCESS | 24320 | Browser inner width/height: 1024/697 12:02:52 INFO - PROCESS | 24320 | __metrics 12:02:52 ERROR - PROCESS | 24320 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3256: TypeError: this.tabs is undefined 12:02:53 INFO - TEST-INFO | 24320: exit 0 12:02:53 INFO - Browser initialized. 12:02:53 INFO - Running cycle 1/1 for kraken test... 12:02:53 INFO - TEST-INFO | started process 24443 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2L7PKX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:03:05 INFO - PROCESS | 24443 | RSS: Main: 157966336 12:03:05 INFO - PROCESS | 24443 | 12:03:08 INFO - PROCESS | 24443 | Cycle 1(1): loaded http://localhost:42044/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42044/tests/kraken/driver.html?testName=audio-beat-detection) 12:03:09 INFO - PROCESS | 24443 | RSS: Main: 163418112 12:03:09 INFO - PROCESS | 24443 | 12:03:12 INFO - PROCESS | 24443 | Cycle 1(1): loaded http://localhost:42044/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42044/tests/kraken/driver.html?testName=audio-dft) 12:03:12 INFO - PROCESS | 24443 | RSS: Main: 163172352 12:03:12 INFO - PROCESS | 24443 | 12:03:15 INFO - PROCESS | 24443 | Cycle 1(1): loaded http://localhost:42044/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42044/tests/kraken/driver.html?testName=audio-fft) 12:03:16 INFO - PROCESS | 24443 | RSS: Main: 159453184 12:03:16 INFO - PROCESS | 24443 | 12:03:18 INFO - PROCESS | 24443 | Cycle 1(1): loaded http://localhost:42044/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42044/tests/kraken/driver.html?testName=audio-oscillator) 12:03:19 INFO - PROCESS | 24443 | RSS: Main: 160317440 12:03:19 INFO - PROCESS | 24443 | 12:03:21 INFO - PROCESS | 24443 | Cycle 1(1): loaded http://localhost:42044/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42044/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:03:21 INFO - PROCESS | 24443 | RSS: Main: 160415744 12:03:21 INFO - PROCESS | 24443 | 12:03:25 INFO - PROCESS | 24443 | Cycle 1(1): loaded http://localhost:42044/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42044/tests/kraken/driver.html?testName=imaging-darkroom) 12:03:25 INFO - PROCESS | 24443 | RSS: Main: 165421056 12:03:25 INFO - PROCESS | 24443 | 12:03:29 INFO - PROCESS | 24443 | Cycle 1(1): loaded http://localhost:42044/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42044/tests/kraken/driver.html?testName=imaging-desaturate) 12:03:29 INFO - PROCESS | 24443 | RSS: Main: 161120256 12:03:29 INFO - PROCESS | 24443 | 12:03:32 INFO - PROCESS | 24443 | Cycle 1(1): loaded http://localhost:42044/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42044/tests/kraken/driver.html?testName=json-parse-financial) 12:03:33 INFO - PROCESS | 24443 | RSS: Main: 162504704 12:03:33 INFO - PROCESS | 24443 | 12:03:35 INFO - PROCESS | 24443 | Cycle 1(1): loaded http://localhost:42044/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42044/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:03:35 INFO - PROCESS | 24443 | RSS: Main: 162521088 12:03:35 INFO - PROCESS | 24443 | 12:03:37 INFO - PROCESS | 24443 | Cycle 1(1): loaded http://localhost:42044/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42044/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:03:38 INFO - PROCESS | 24443 | RSS: Main: 162635776 12:03:38 INFO - PROCESS | 24443 | 12:03:40 INFO - PROCESS | 24443 | Cycle 1(1): loaded http://localhost:42044/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42044/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:03:41 INFO - PROCESS | 24443 | RSS: Main: 163110912 12:03:41 INFO - PROCESS | 24443 | 12:03:44 INFO - PROCESS | 24443 | Cycle 1(1): loaded http://localhost:42044/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42044/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:03:44 INFO - PROCESS | 24443 | RSS: Main: 163401728 12:03:44 INFO - PROCESS | 24443 | 12:03:47 INFO - PROCESS | 24443 | Cycle 1(1): loaded http://localhost:42044/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42044/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:03:48 INFO - PROCESS | 24443 | RSS: Main: 162889728 12:03:48 INFO - PROCESS | 24443 | 12:03:50 INFO - PROCESS | 24443 | Cycle 1(1): loaded http://localhost:42044/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42044/tests/kraken/driver.html?testName=ai-astar) 12:03:50 INFO - PROCESS | 24443 | RSS: Main: 164966400 12:03:50 INFO - PROCESS | 24443 | 12:03:50 INFO - PROCESS | 24443 | __start_tp_report 12:03:50 INFO - PROCESS | 24443 | _x_x_mozilla_page_load 12:03:50 INFO - PROCESS | 24443 | _x_x_mozilla_page_load_details 12:03:50 INFO - PROCESS | 24443 | |i|pagename|runs| 12:03:50 INFO - PROCESS | 24443 | |0;ai-astar;108;114;116;115;114;116;115;116;116;116 12:03:50 INFO - PROCESS | 24443 | |1;audio-beat-detection;166;126;127;177;130;130;133;130;147;138 12:03:50 INFO - PROCESS | 24443 | |2;audio-dft;163;168;163;168;175;166;163;162;162;162 12:03:50 INFO - PROCESS | 24443 | |3;audio-fft;76;77;78;100;77;77;77;77;106;77 12:03:50 INFO - PROCESS | 24443 | |4;audio-oscillator;85;84;107;87;85;89;101;88;85;110 12:03:50 INFO - PROCESS | 24443 | |5;imaging-gaussian-blur;118;118;119;118;119;117;122;118;118;119 12:03:50 INFO - PROCESS | 24443 | |6;imaging-darkroom;150;150;152;150;150;151;150;150;152;154 12:03:50 INFO - PROCESS | 24443 | |7;imaging-desaturate;96;96;96;96;96;97;96;96;96;96 12:03:50 INFO - PROCESS | 24443 | |8;json-parse-financial;64;65;64;66;65;69;66;66;67;65 12:03:50 INFO - PROCESS | 24443 | |9;json-stringify-tinderbox;68;69;68;69;69;69;67;68;68;69 12:03:50 INFO - PROCESS | 24443 | |10;stanford-crypto-aes;87;86;89;86;87;85;92;85;88;87 12:03:50 INFO - PROCESS | 24443 | |11;stanford-crypto-ccm;147;138;153;152;134;134;135;135;216;139 12:03:50 INFO - PROCESS | 24443 | |12;stanford-crypto-pbkdf2;182;181;179;177;180;180;179;179;177;178 12:03:50 INFO - PROCESS | 24443 | |13;stanford-crypto-sha256-iterative;71;70;70;70;77;69;71;72;70;69 12:03:50 INFO - PROCESS | 24443 | __end_tp_report 12:03:50 INFO - PROCESS | 24443 | __start_cc_report 12:03:50 INFO - PROCESS | 24443 | _x_x_mozilla_cycle_collect,795 12:03:50 INFO - PROCESS | 24443 | __end_cc_report 12:03:50 INFO - PROCESS | 24443 | __startTimestamp1483214630558__endTimestamp 12:03:50 INFO - PROCESS | 24443 | 12:03:50 INFO - PROCESS | 24443 | ------- Summary: start ------- 12:03:50 INFO - PROCESS | 24443 | Number of tests: 14 12:03:50 INFO - PROCESS | 24443 | 12:03:50 INFO - PROCESS | 24443 | [#0] ai-astar Cycles:10 Average:114.60 Median:116.00 stddev:2.46 (2.1%) stddev-sans-first:0.87 12:03:50 INFO - PROCESS | 24443 | Values: 108.0 114.0 116.0 115.0 114.0 116.0 115.0 116.0 116.0 116.0 12:03:50 INFO - PROCESS | 24443 | 12:03:50 INFO - PROCESS | 24443 | [#1] audio-beat-detection Cycles:10 Average:140.40 Median:135.50 stddev:17.67 (13.0%) stddev-sans-first:16.13 12:03:50 INFO - PROCESS | 24443 | Values: 166.0 126.0 127.0 177.0 130.0 130.0 133.0 130.0 147.0 138.0 12:03:50 INFO - PROCESS | 24443 | 12:03:50 INFO - PROCESS | 24443 | [#2] audio-dft Cycles:10 Average:165.20 Median:164.50 stddev:4.18 (2.5%) stddev-sans-first:4.36 12:03:50 INFO - PROCESS | 24443 | Values: 163.0 168.0 163.0 168.0 175.0 166.0 163.0 162.0 162.0 162.0 12:03:50 INFO - PROCESS | 24443 | 12:03:50 INFO - PROCESS | 24443 | [#3] audio-fft Cycles:10 Average:82.20 Median:77.00 stddev:11.06 (14.4%) stddev-sans-first:11.50 12:03:50 INFO - PROCESS | 24443 | Values: 76.0 77.0 78.0 100.0 77.0 77.0 77.0 77.0 106.0 77.0 12:03:50 INFO - PROCESS | 24443 | 12:03:50 INFO - PROCESS | 24443 | [#4] audio-oscillator Cycles:10 Average:92.10 Median:85.00 stddev:9.95 (11.7%) stddev-sans-first:10.22 12:03:50 INFO - PROCESS | 24443 | Values: 85.0 84.0 107.0 87.0 85.0 89.0 101.0 88.0 85.0 110.0 12:03:50 INFO - PROCESS | 24443 | 12:03:50 INFO - PROCESS | 24443 | [#5] imaging-gaussian-blur Cycles:10 Average:118.60 Median:118.50 stddev:1.35 (1.1%) stddev-sans-first:1.41 12:03:50 INFO - PROCESS | 24443 | Values: 118.0 118.0 119.0 118.0 119.0 117.0 122.0 118.0 118.0 119.0 12:03:50 INFO - PROCESS | 24443 | 12:03:50 INFO - PROCESS | 24443 | [#6] imaging-darkroom Cycles:10 Average:150.90 Median:150.50 stddev:1.37 (0.9%) stddev-sans-first:1.41 12:03:50 INFO - PROCESS | 24443 | Values: 150.0 150.0 152.0 150.0 150.0 151.0 150.0 150.0 152.0 154.0 12:03:50 INFO - PROCESS | 24443 | 12:03:50 INFO - PROCESS | 24443 | [#7] imaging-desaturate Cycles:10 Average:96.10 Median:96.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 12:03:50 INFO - PROCESS | 24443 | Values: 96.0 96.0 96.0 96.0 96.0 97.0 96.0 96.0 96.0 96.0 12:03:50 INFO - PROCESS | 24443 | 12:03:50 INFO - PROCESS | 24443 | [#8] json-parse-financial Cycles:10 Average:65.70 Median:66.00 stddev:1.49 (2.3%) stddev-sans-first:1.45 12:03:50 INFO - PROCESS | 24443 | Values: 64.0 65.0 64.0 66.0 65.0 69.0 66.0 66.0 67.0 65.0 12:03:50 INFO - PROCESS | 24443 | 12:03:50 INFO - PROCESS | 24443 | [#9] json-stringify-tinderbox Cycles:10 Average:68.40 Median:69.00 stddev:0.70 (1.0%) stddev-sans-first:0.73 12:03:50 INFO - PROCESS | 24443 | Values: 68.0 69.0 68.0 69.0 69.0 69.0 67.0 68.0 68.0 69.0 12:03:50 INFO - PROCESS | 24443 | 12:03:50 INFO - PROCESS | 24443 | [#10] stanford-crypto-aes Cycles:10 Average:87.20 Median:87.00 stddev:2.10 (2.4%) stddev-sans-first:2.22 12:03:50 INFO - PROCESS | 24443 | Values: 87.0 86.0 89.0 86.0 87.0 85.0 92.0 85.0 88.0 87.0 12:03:50 INFO - PROCESS | 24443 | 12:03:50 INFO - PROCESS | 24443 | [#11] stanford-crypto-ccm Cycles:10 Average:148.30 Median:143.00 stddev:24.89 (17.4%) stddev-sans-first:26.40 12:03:50 INFO - PROCESS | 24443 | Values: 147.0 138.0 153.0 152.0 134.0 134.0 135.0 135.0 216.0 139.0 12:03:50 INFO - PROCESS | 24443 | 12:03:50 INFO - PROCESS | 24443 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.20 Median:179.50 stddev:1.62 (0.9%) stddev-sans-first:1.36 12:03:50 INFO - PROCESS | 24443 | Values: 182.0 181.0 179.0 177.0 180.0 180.0 179.0 179.0 177.0 178.0 12:03:50 INFO - PROCESS | 24443 | 12:03:50 INFO - PROCESS | 24443 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.90 Median:70.50 stddev:2.33 (3.3%) stddev-sans-first:2.47 12:03:50 INFO - PROCESS | 24443 | Values: 71.0 70.0 70.0 70.0 77.0 69.0 71.0 72.0 70.0 69.0 12:03:50 INFO - PROCESS | 24443 | -------- Summary: end -------- 12:03:50 INFO - PROCESS | 24443 | 12:03:51 INFO - TEST-INFO | 24443: exit 0 12:03:51 INFO - TEST-OK | kraken | took 64007ms 12:03:51 INFO - SUITE-END | took 1183s 12:03:52 INFO - Completed test suite (00:19:44) 12:03:52 INFO - javascript benchmark 12:03:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6271.291996811195, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2586.965213914434, 2616.0, 2617.437250991037, 2621.5412302847, 2658.5746280595095, 4817.181079216532, 4857.757112144387, 4875.098106850235, 4885.828995985137, 4901.852944411669, 4833.419989601244, 4846.539578740023, 4887.975560122203, 4888.462269150391, 4895.290182923474, 4455.576720211575, 4460.79926403311, 4554.294084416921, 4570.13167498176, 4622.39908811854, 4500.97749511252, 4511.729296242236, 4552.658550608701, 4559.088182363524, 4597.494275629678, 4409.4488188976375, 4424.646028317727, 4444.799984000728, 4527.275635898273, 4555.430571178603, 4420.778961051935, 4476.798544065525, 4481.663875209372, 4513.50351461339, 4541.069080838421, 4454.064646424223, 4456.08650226706, 4456.19788438081, 4471.709338892962, 4489.012417268195, 4692.741899195543, 4705.035467729107, 4840.15297322967, 4846.709197448165, 4854.199057155565, 5121.590272778169, 5155.53600175986, 5159.329287192641, 5159.71621560814, 5161.922571161435, 5177.275181474596, 5185.766640501149, 5192.506711862367, 5193.454687257821, 5200.843974680728, 4825.348577941968, 4827.782749776268, 4830.3479030331155, 4836.202026665631, 4862.5380588844, 5207.973960130175, 5247.895042099137, 5266.552343050884, 5267.947320526822, 5275.419703832652, 5055.241713742968, 5065.062963351718, 5079.390473143247, 5080.212567052113, 5101.617378696613, 4995.0509403214, 5160.819371321985, 5178.197379406198, 5230.032443997872, 5301.125314323171, 5127.846164615067, 5130.87172820675, 5142.30578871855, 5183.637145399785, 5231.764570594332, 5067.594592432597, 5196.584273258206, 5199.4020687621405, 5223.268742376071, 5259.0796610593, 4339.587739164775, 4343.391925130484, 4349.913001740011, 4370.104128653618, 4439.511653718088, 3771.0, 3771.8114094295174, 3778.6599206071587, 3809.3714537101623, 3811.104390468238, 4451.77741112943, 4478.440194975628, 4486.551344865553, 4497.955020449819, 4531.660125490602, 5144.9742751286, 5184.792608295701, 5207.3490813648295, 5222.2427747976335, 5237.816676416307, 4495.370648109268, 4505.05393867285, 4525.570070843265, 4532.410786597721, 4550.385697930737, 4676.3453116563705, 4676.438827340741, 4676.672632915731, 4700.95299047012, 4719.669623126349, 4736.526347365236, 4742.739149346681, 4796.424429068534, 4797.808087676453, 4831.0, 5255.159174532057, 5262.158054711228, 5264.368275806928, 5309.787608495617, 5326.573874089986, 4811.133995880775, 4830.541098595628, 4841.709497430165, 4846.781894814671, 4860.927086093782, 5016.2224855147515, 5083.745812709276, 5085.8728531787, 5185.092608793521, 5194.246834208943, 5169.586433085269, 5215.817446389358, 5219.660722053054, 5226.477352264743, 5230.712310822942, 5006.07387633289, 5040.344755181803, 5040.571551418024, 5045.899082018265, 5055.140626093499, 4990.700557966534, 5004.1492946198505, 5006.324146240211, 5074.137396642654, 5100.719460429712, 4963.205887058198, 4972.726500042532, 4974.353334066693, 5009.0482808266315, 5020.42265139499, 5084.669496482717, 5088.84733457982, 5091.439941606494, 5099.2860999460845, 5106.183010718416, 4822.879428014187, 4844.152273352079, 4880.316755654282, 4945.035718035093, 4948.05986862495], "value": 4754.179707886849, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [507.8831868670206, 517.7488917874832, 518.9922151167731, 526.8946210757847, 527.9102552566063, 37142.814285928565, 38949.61050389495, 38956.61043389565, 41929.79035104828, 42121.78939105308, 38580.80709596448, 38823.41764873529, 38831.61168388315, 38915.416268755995, 38993.41509877354, 7602.2397760224085, 7777.144514103444, 7791.766247012599, 9457.10157535033, 9811.264155188353, 9682.709518714415, 9780.510974451285, 9785.363951343175, 9789.755256118618, 9792.069753373418, 9752.90247097527, 9781.070798274188, 9808.871979722351, 9821.312508124434, 9823.263255255848, 9686.854697179513, 9725.513724313827, 9735.415875047483, 9736.464494452803, 9755.219582433388, 9451.243900488013, 9518.476483793389, 9523.666671666459, 9528.857067143997, 9528.95235523818, 33200.66799332, 33243.50134747981, 33437.498437523456, 33586.496202556984, 33609.49585756216, 3549.929001419983, 3638.1450359165574, 3647.1429214134655, 3657.122290650225, 3691.3171063353097, 25066.624000640004, 25110.372240693945, 25302.74697253022, 25521.48957020849, 26544.8672756635, 3336.6830151135605, 3446.6553344665585, 3542.6634469725336, 3574.1064733816547, 3580.820958952042, 238845.0, 240443.0, 240938.7953060241, 240981.0, 242654.0, 45852.541474585494, 46389.7680511602, 46401.0, 46401.76799115983, 46408.303875441896, 47561.524384756405, 47636.0, 47830.5216947833, 47882.28176577354, 47882.521174788504, 11884.286942783288, 11934.761304774029, 11945.940270298594, 12002.819957700642, 12008.219465734699, 8294.294984926228, 8449.873251901226, 9752.073553012453, 9948.950255248677, 10066.09405153525, 14942.551723448314, 15125.319360628799, 15146.848531514765, 15294.0, 15316.770248446282, 6222.62664240147, 6268.0, 6323.683815809283, 6324.683765811691, 6592.406683398517, 744.9441291903021, 745.1244787374812, 766.1878408886598, 776.9899131129622, 781.1485480825927, 27116.457670846474, 27694.3076423087, 27982.720172798417, 28042.43915121727, 28405.857970710014, 919.0992827029415, 980.3921568627354, 981.3719219699398, 993.3394292795355, 996.7059717383389, 13940.721185576434, 14793.482228122179, 14981.101133932, 14989.70020599582, 15043.774343384857, 366666.0, 369104.0, 369290.0, 370224.0, 371211.0, 49148.75425622849, 49207.26189107166, 49276.50723492791, 49319.0, 49740.75129624401, 1170.6897672116895, 1180.9055275577932, 1210.4673943464848, 1218.0925210717894, 1224.3449754381363, 25305.746942531074, 25871.0, 27071.86464067667, 27088.0, 27186.72813271842, 40572.1885562293, 41063.38404923869, 41099.383509246785, 41237.0, 41308.0, 297415.0, 298069.0, 299453.0, 299492.0, 300372.0, 22274.0, 22780.633162010334, 23363.299101026998, 23970.52058958846, 24577.385565360295, 39811.80094099511, 39903.20193596054, 40067.0, 40181.397279040255, 40348.0, 34255.82872085624, 34762.478562822085, 34771.0, 34865.30269394648, 35068.0, 34349.656503435646, 34764.13089672779, 34963.82518087393, 35332.0, 35366.823165885035, 33265.0, 33423.0, 33785.83107084449, 34022.14944626404, 34343.141421464665, 28816.423671526034, 31110.0, 31112.0, 31266.843665781525, 31342.37315253728, 23217.303480894923, 23277.18529851515, 23564.347526133475, 23676.0, 23940.88029559841], "value": 17063.978600314284, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [366.28208710926583, 371.89586915663614, 372.5808465476338, 379.32669511616876, 389.8635477582845, 15133.697326053487, 15216.391344346226, 16553.83446165541, 16553.999999999985, 16637.084960327178, 16118.11350375729, 16404.75392869108, 16419.261133249, 16569.088700121487, 16588.08765517899, 4471.217536931033, 4737.763111844394, 4766.022965292124, 4766.809327626908, 4777.856664300077, 4672.509386514418, 4701.670883038189, 4711.128441238365, 4712.151812673717, 4732.810687572493, 4636.258198688193, 5052.317937078503, 5089.083964886319, 5096.337476128117, 5100.183970564711, 5042.82350117746, 5073.39119305682, 5078.263651770472, 5082.745862706868, 5084.949150508503, 4413.889652758675, 4652.441706995182, 4693.155232058228, 4693.78877950493, 4695.765211739399, 12187.817182742267, 12282.631521054383, 12365.0, 12384.504619815196, 12409.937950310281, 2074.844386670991, 2121.8832964186963, 2136.8290536757177, 2164.2641501889275, 2175.59751445983, 8730.17063378978, 9461.101195386427, 9717.222622190278, 12363.752724945542, 12427.943624791904, 1738.2699266308182, 1743.7297218931085, 1744.5464179313342, 1754.4911975527082, 1798.8830726002768, 61986.0, 64744.67627661832, 65814.3418565813, 65889.34110658927, 67118.66440667814, 9137.862932056025, 9398.20115290208, 9412.858807117898, 9471.0, 9588.089131532493, 12804.039697022761, 12836.935815320863, 12884.742305154032, 12885.355732213351, 12899.90350820172, 6664.566803157779, 6787.389134977876, 6823.522353435311, 6938.722451102, 6964.408025317804, 6359.982402815527, 6386.584871983307, 6544.345565443417, 6598.406143447113, 6615.106960560359, 3790.5261842269715, 3890.727649064595, 3967.7222594418113, 4065.308897487434, 4081.8775436736946, 1841.8526517878538, 1858.8605854560692, 1858.9070546472622, 1872.2885303584824, 1895.0808857704003, 810.8662070758259, 839.2656425627575, 841.5413599588239, 858.3390789092364, 877.368294827723, 15355.474676953967, 15795.131267780382, 16030.839691602934, 16119.758203626954, 16737.38331752618, 829.3613917283658, 830.2071521696765, 835.543502103825, 838.773531146587, 862.2196911794836, 8979.865302020475, 9073.863892041625, 9127.406718563272, 9297.349185557081, 9401.491650056709, 251306.0, 256095.0, 341584.0, 347162.0, 347785.0, 74213.32080112815, 75448.24551754522, 76029.0, 77311.0, 77473.0, 918.4581097152712, 965.1603105298435, 966.3235734985678, 980.5832521178471, 981.40134517944, 15141.318640660977, 15183.0, 15326.693466130839, 15642.843571564139, 15952.0, 33870.66129338675, 34701.091439971045, 35071.47392789059, 35770.64229357673, 36309.27381452303, 46933.530664694284, 47804.0, 47857.76071119762, 47858.0, 47901.28148077712, 7479.700811967678, 7531.246875312425, 7648.273414025881, 7750.379969602531, 7787.766367008999, 11367.48846301935, 11861.347625880659, 12058.397080146133, 12062.336571488302, 12100.334481603244, 4791.976040119777, 5126.641135120506, 9228.0, 9323.766905827408, 9791.314607977372, 9063.229625481908, 10265.640702575673, 10284.948575257076, 10344.53449594755, 10481.056704896597, 5708.457696518824, 7846.60766961661, 8858.468491890508, 10339.948300258451, 10581.36511809294, 7694.1536430993665, 7815.53106813593, 7901.288884000468, 7964.920350796418, 8000.39997000239, 7746.56688512627, 9138.0, 10048.207066049199, 10086.697399078039, 10388.065074143364], "value": 7720.033681634497, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1286.5625687266333, 1310.842698876135, 1312.8949684025276, 1320.0693511074687, 1323.6426164935467, 6118.816435506931, 6254.874902501959, 6957.965210173948, 7440.851182976344, 7450.590217538033, 6639.336066393358, 6863.107795986515, 6869.55347902386, 7027.402670772991, 7037.331453511921, 2074.8962551872382, 2135.263334149716, 2139.839512036596, 2141.7751136130714, 2142.5607750411127, 2091.236698605006, 2115.809577138057, 2166.880821554814, 2171.348595421375, 2179.9128034878586, 2103.5897999890026, 2118.4597927528457, 2133.232036466871, 2146.774588668191, 2146.8819214943173, 2078.7193728846564, 2079.1683326669377, 2088.979110208901, 2090.2057218256964, 2099.8740075595515, 2062.597793430282, 2079.740032495938, 2080.6566916458764, 2091.205341970057, 2102.4743814046487, 7357.301056399633, 7563.508371955805, 7602.657880395395, 7644.732434364771, 7655.578943158124, 4260.8721738347895, 4387.451568553931, 4390.868273951786, 4391.582799634061, 4395.758233297167, 2719.5648696208514, 2895.348546577003, 2932.7653787697145, 2938.6620538637903, 2959.4377068356944, 1150.6317978246964, 1175.2184797109912, 1184.538030168235, 1212.121212121205, 1218.8720184380602, 9843.409395436296, 9911.355761875528, 9934.25493088014, 9969.052939970692, 9975.451350175737, 3770.792606406674, 3930.390789427643, 3960.9009774755864, 4038.818253178614, 4057.3102572562734, 7945.364370850318, 7971.481853679492, 7990.200979901963, 8002.03975522941, 8019.278264956182, 4425.668074894395, 4451.933221001687, 4498.977505112519, 4514.571115743999, 4556.7721613919175, 4010.8796736097966, 4121.5466298707115, 4193.22425351311, 4194.035371864427, 4200.936985945214, 3222.323312104484, 3271.8364081795817, 3335.282914173464, 3337.7830441021256, 3357.3956687795944, 942.0861764088822, 983.3608154699359, 985.113397941858, 987.2792861211356, 993.2419617277523, 787.118427361359, 811.1280373598405, 814.4502460839028, 818.2758258940761, 825.8430898129336, 4665.300204969281, 4813.133635945564, 5005.74971251436, 5013.774380152902, 5126.538936403107, 801.0427539090731, 849.7578190215759, 854.3933806996978, 892.2817132208505, 893.9374243802871, 3239.433099207676, 3408.897733068012, 3478.252175782219, 3580.212353282274, 3661.56061272649, 8840.204381605687, 8927.24118449939, 9050.86423703645, 9077.137671921157, 9116.954415228014, 8941.508217048126, 8999.550022498848, 9025.954870225607, 9042.412243204171, 9093.81812363749, 6815.795526134125, 7045.436365090781, 7066.540674856118, 7080.291970802981, 7086.645667716593, 4746.857594272177, 4975.676581022222, 5003.749812509433, 5061.620378471555, 5365.9463405367005, 8156.796080097808, 8168.877466838121, 8221.79445513867, 8237.341012719087, 8249.257566819015, 8231.753047408347, 8294.543800091053, 8304.460210086325, 8319.376046796635, 8452.36607254446, 6454.5159113065765, 6626.734930602722, 6631.602103873783, 6637.435817955335, 6642.169728783902, 6784.151981002375, 6941.062956500808, 7031.859362812817, 7237.0, 7239.746608868664, 6069.453749162597, 6212.130301757667, 6272.247330320391, 6404.327545607831, 6425.228972523327, 6395.648239346694, 6414.03789431589, 6424.261209960914, 6443.259025212161, 6452.096706461193, 5613.943860561342, 5704.714764261853, 5715.685637289989, 5725.370209277059, 5729.140628905698, 4178.435911151955, 4214.115035842385, 4214.536400995949, 4294.677899157638, 4326.0, 4275.29457639486, 4334.28484300087, 4350.0, 4454.688171827941, 4551.886202844956], "value": 3934.27611450251, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [340.95397121388623, 350.62658268943574, 358.45156909927806, 366.0354964668099, 367.13173345038973, 4713.575778179963, 4909.656324057318, 4952.207646776516, 4977.427595826481, 4984.327115839361, 4798.592119669832, 4872.293517439969, 4966.826161084363, 4968.726720030397, 4970.179920313153, 3996.220736956295, 4021.718479706422, 4040.151568170688, 4040.9797951010205, 4044.0, 3933.744306620075, 3988.6239247459725, 3990.8603198888045, 4031.778252196128, 4042.1915616876595, 3941.7437866538585, 3955.545112312078, 3960.0, 3961.9603803962023, 3983.243183795084, 3938.9606103939022, 3944.2505923874514, 3953.9406908896103, 3962.1085255817497, 3969.6625786808154, 3573.821308934569, 3577.3918433866047, 3588.4258518637153, 3605.188832512664, 3608.639136086397, 4673.532646735299, 4821.252705830602, 4880.8047678092835, 4893.0947774661445, 4921.163402221595, 4501.549845015505, 4505.909881802379, 4544.363789069533, 4546.20441422753, 4577.793999270008, 5202.817901373433, 5208.895822083575, 5210.583153347723, 5212.29633999409, 5226.764795584208, 3726.4782930389765, 3886.650201481852, 3898.3567711327596, 3929.7445666031904, 3937.3897045957638, 5397.8380648580605, 5410.431904649994, 5412.621116521845, 5415.268938693304, 5438.374586922515, 4000.19996000801, 4003.2994226010333, 4016.196760647882, 4024.2957482440456, 4031.6774658027875, 3961.069148750041, 4092.7748973806133, 4094.5700701426517, 4105.4252404663375, 4106.638682757367, 4477.507474177838, 4498.257787465097, 4502.212112880298, 4510.954890451054, 4523.163214805271, 5185.611079169077, 5191.844244672666, 5202.635815492955, 5204.21936709489, 5243.790248390022, 1299.466629377325, 1317.6508225320297, 1360.6530334764573, 1374.415873253863, 1390.8887289017057, 977.5942983661843, 998.3810037776625, 1005.5073014222978, 1006.8137394581879, 1016.3038318751679, 4075.0, 4294.592013758688, 4342.652587792969, 4353.238183317907, 4381.583749543852, 5100.285959965608, 5101.642884998015, 5110.0, 5118.539331460112, 5129.102407078821, 4364.956350436519, 4372.519022907478, 4383.238559707428, 4389.582989615981, 4389.670774691911, 2652.7082020977678, 2717.945641087167, 2728.871480245412, 2732.6720793504905, 2743.4238809849758, 4717.599004084623, 4735.976320118378, 4737.407824021997, 4738.834140805125, 4751.976240118777, 5061.4179369373, 5228.039485193052, 5257.185136303879, 5267.920981185285, 5267.9736601316745, 4692.577668009895, 4715.27892319301, 4735.550122738335, 4745.962583946208, 4749.430068391746, 5128.486986414318, 5132.820351287687, 5152.613553983391, 5194.35070616173, 5201.661891977009, 4822.493638168082, 5089.949100509096, 5115.309433226466, 5115.693058416507, 5125.513601055801, 4521.389612402284, 4632.814687412466, 4641.582257596832, 4647.883802904955, 4663.11143986686, 3883.8446462141196, 3886.5724770274683, 3893.1824316892635, 3934.488516492952, 3934.586868378779, 5122.847359344118, 5137.717425541481, 5156.510131537498, 5157.200633901752, 5168.896622067613, 4961.305417241661, 5176.792928282826, 5182.922256166236, 5199.636025478331, 5209.166533354645, 5181.81863634771, 5200.583953283804, 5208.713520756395, 5220.608454365862, 5238.266642670105, 4886.65793394459, 5121.411037730709, 5130.307408499804, 5140.177571588528, 5153.582764739726, 5152.510511501401, 5197.376314842092, 5201.349831271091, 5204.2974198484235, 5211.60912931524, 5053.343065401621, 5061.645684802029, 5074.188129899198, 5075.680323115943, 5077.090640207094], "value": 3933.0095432312146, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [677.0, 679.6635665345655, 732.1251104929607, 744.6723441685658, 756.9490198798194, 12487.625371238855, 12900.935495322521, 13901.374438150282, 14094.64763380916, 14202.857971420295, 13645.658858528543, 14105.435782568686, 14108.365123569438, 14878.55364339069, 14900.329485173166, 780.7345502529167, 803.3653413803117, 804.6620419423841, 827.8178800663844, 828.3207769628907, 786.4927122006302, 794.003525575402, 794.1264608930188, 794.3406972213078, 794.9880751788698, 853.6158728572136, 860.1269711243093, 860.4321148042293, 861.1345597674328, 861.29804209569, 849.6176720475811, 853.0445900591316, 853.3983541603155, 854.0690647194588, 854.2781349759443, 624.7282432142033, 628.6730899932055, 628.789355565886, 628.8993760998264, 629.4586655476287, 14046.0, 14314.713705725932, 14335.713285734228, 14410.351534180987, 14492.782608260884, 12818.423170957329, 13231.603051908458, 13416.262105584188, 13444.663883402894, 13545.187288762705, 10272.589096436133, 10277.17782577392, 10310.948445257725, 10523.0, 10688.19838512107, 3186.1715953851935, 3209.0, 3232.9676703233135, 3235.4176248275303, 3276.6231883333485, 62684.37315626831, 62927.6853615729, 63300.36699632991, 63410.04884926684, 65572.0, 9847.409155450696, 9894.208463322917, 10037.347572407916, 10108.191344692406, 10156.23828212887, 18202.45392638223, 18223.17995690197, 18226.45320640383, 18283.451496455127, 18362.173702183434, 6335.194469576149, 6352.428281454691, 6357.523185761086, 6486.4486518646445, 6562.409383155539, 3598.442241452579, 3611.8013509257244, 3644.6173151818675, 3651.653092956165, 3732.757370770891, 2783.6798768141516, 2837.2481292457514, 2843.9857800710865, 2860.413615208877, 2872.066578361999, 466.20512026993896, 467.04023232257356, 469.3194867442154, 474.11814025911775, 477.51293680446446, 615.7383112177307, 636.5989426661174, 645.8245992294073, 647.5920170292685, 651.6741629185407, 7883.842323153504, 8512.489250644981, 8792.296121575055, 8817.911820881709, 8971.50656713874, 483.8887055977145, 489.84814707440097, 493.62977766674993, 577.9423654711867, 661.8080756580538, 3648.492342054865, 3919.862804801818, 4006.759594424344, 4012.7592344459426, 4105.527864295585, 62358.688206559586, 62419.0, 63014.36985630085, 65641.0, 65993.34006659871, 54429.72785136049, 55364.0, 56665.0, 56847.14729279064, 58717.0, 48555.757221213666, 48810.0, 49149.26276105861, 49941.75029124758, 50438.49561504338, 6939.201991770809, 7207.279272072751, 7232.204457509775, 7343.412526997934, 7351.264873512606, 33860.661393386734, 33867.0, 34072.82963585166, 34815.56291059602, 35283.0, 42896.571034289256, 42899.35650965301, 43707.562924371625, 45700.771496142304, 45965.31052034289, 9735.653138871287, 9925.801483970425, 9954.950225248827, 10079.395236285847, 10155.39067655943, 11108.277961932448, 11219.67252300696, 11222.607208747655, 11319.264247823865, 11427.657170284905, 10971.451427428437, 11664.130914208983, 12286.262824230575, 12316.630501084981, 13303.201807891557, 12149.69625759363, 12333.75332493363, 12621.242725436503, 13135.540256090992, 13224.338783061, 11328.207025508136, 11785.528578856749, 11851.348175850066, 12217.328046957502, 12546.184498007966, 10565.600347723424, 10647.94676026615, 10914.072303854262, 11061.61284355076, 11223.719407014563, 9911.603535858485, 10450.529726162189, 10653.9467302663, 10828.945855270673, 10885.0], "value": 6276.67546234417, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1579.8000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 114.0, 116.0, 115.0, 114.0, 116.0, 115.0, 116.0, 116.0, 116.0], "value": 114.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 126.0, 127.0, 177.0, 130.0, 130.0, 133.0, 130.0, 147.0, 138.0], "value": 140.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 168.0, 163.0, 168.0, 175.0, 166.0, 163.0, 162.0, 162.0, 162.0], "value": 165.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 78.0, 100.0, 77.0, 77.0, 77.0, 77.0, 106.0, 77.0], "value": 82.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 107.0, 87.0, 85.0, 89.0, 101.0, 88.0, 85.0, 110.0], "value": 92.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 119.0, 118.0, 119.0, 117.0, 122.0, 118.0, 118.0, 119.0], "value": 118.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 150.0, 152.0, 150.0, 150.0, 151.0, 150.0, 150.0, 152.0, 154.0], "value": 150.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 96.0, 96.0, 97.0, 96.0, 96.0, 96.0, 96.0], "value": 96.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 64.0, 66.0, 65.0, 69.0, 66.0, 66.0, 67.0, 65.0], "value": 65.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 68.0, 69.0, 69.0, 69.0, 67.0, 68.0, 68.0, 69.0], "value": 68.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 89.0, 86.0, 87.0, 85.0, 92.0, 85.0, 88.0, 87.0], "value": 87.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 138.0, 153.0, 152.0, 134.0, 134.0, 135.0, 135.0, 216.0, 139.0], "value": 148.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 181.0, 179.0, 177.0, 180.0, 180.0, 179.0, 179.0, 177.0, 178.0], "value": 179.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 70.0, 70.0, 70.0, 77.0, 69.0, 71.0, 72.0, 70.0, 69.0], "value": 70.9, "unit": "score"}]}]} 12:03:52 INFO - Return code: 0 12:03:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:03:52 ERROR - # TBPL SUCCESS # 12:03:52 INFO - Running post-action listener: _resource_record_post_action 12:03:52 INFO - [mozharness: 2016-12-31 20:03:52.338381Z] Finished run-tests step (success) 12:03:52 INFO - Running post-run listener: _resource_record_post_run 12:03:52 INFO - Total resource usage - Wall time: 1200s; CPU: 14.0%; Read bytes: 4096; Write bytes: 382369792; Read time: 16; Write time: 192984 12:03:52 INFO - TinderboxPrint: CPU usage
13.5% 12:03:52 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 12:03:52 INFO - TinderboxPrint: I/O write bytes / time
382,369,792 / 192,984 12:03:52 INFO - TinderboxPrint: CPU idle
8,279.9 (86.5%) 12:03:52 INFO - TinderboxPrint: CPU user
1,236.8 (12.9%) 12:03:52 INFO - TinderboxPrint: Swap in / out
0 / 0 12:03:52 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 99749888; Read time: 0; Write time: 68508 12:03:52 INFO - run-tests - Wall time: 1185s; CPU: 14.0%; Read bytes: 0; Write bytes: 282619904; Read time: 0; Write time: 124476 12:03:52 INFO - Running post-run listener: _upload_blobber_files 12:03:52 INFO - Blob upload gear active. 12:03:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:03:52 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:03:52 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:03:52 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:03:53 INFO - (blobuploader) - INFO - Open directory for files ... 12:03:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:03:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:03:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:03:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:03:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:03:54 INFO - (blobuploader) - INFO - Done attempting. 12:03:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:03:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:03:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:03:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:03:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:03:57 INFO - (blobuploader) - INFO - Done attempting. 12:03:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:03:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:03:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:03:58 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:03:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:03:58 INFO - (blobuploader) - INFO - Done attempting. 12:03:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:03:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:03:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:03:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:03:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:03:59 INFO - (blobuploader) - INFO - Done attempting. 12:03:59 INFO - (blobuploader) - INFO - Iteration through files over. 12:03:59 INFO - Return code: 0 12:03:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:03:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:03:59 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/baca659f8dcf07952f87bdd783dfcd3bd8592e5b56521f4b2b2da42570bfea996f3a8a7eb1dff759bbc9d523f14456f92100887721aa467c15451e627dca320f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/271cd33d61c329874bd7397ab893d516e912653fb5aace88b495d7867aae0020d0032b7a32dd4c8a3d69bf4df01179b63696b3b02a32f2360c4efb6108b48467", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b98e45aa88ff2a821e4ad8d50d52fe611964f19ccc314f8000cd090251fad6cc983f3fa55b2187c0ecec64e4196b8e2f83259e5a23b4c5d0803d7513752241ed", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3c57f7956541fe969d9909e3b46b708d2b5a3d2ef5637a82a26c6cd4a4ab00d272bc6d170ddcaf3d9f5c0ed539be1eea684774dcfa9964c3ada4db74c8795e39"} 12:03:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:03:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:03:59 INFO - Contents: 12:03:59 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/baca659f8dcf07952f87bdd783dfcd3bd8592e5b56521f4b2b2da42570bfea996f3a8a7eb1dff759bbc9d523f14456f92100887721aa467c15451e627dca320f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/271cd33d61c329874bd7397ab893d516e912653fb5aace88b495d7867aae0020d0032b7a32dd4c8a3d69bf4df01179b63696b3b02a32f2360c4efb6108b48467", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b98e45aa88ff2a821e4ad8d50d52fe611964f19ccc314f8000cd090251fad6cc983f3fa55b2187c0ecec64e4196b8e2f83259e5a23b4c5d0803d7513752241ed", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3c57f7956541fe969d9909e3b46b708d2b5a3d2ef5637a82a26c6cd4a4ab00d272bc6d170ddcaf3d9f5c0ed539be1eea684774dcfa9964c3ada4db74c8795e39"} 12:03:59 INFO - Running post-run listener: copy_logs_to_upload_dir 12:03:59 INFO - Copying logs to upload dir... 12:03:59 INFO - mkdir: /builds/slave/test/build/upload/logs 12:03:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.728987 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-12-31 12:03:59.048066) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-31 12:03:59.052001) ========= 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=41a7e32711b8e08598a63237000001d6-1483212920.537035-964079285 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/baca659f8dcf07952f87bdd783dfcd3bd8592e5b56521f4b2b2da42570bfea996f3a8a7eb1dff759bbc9d523f14456f92100887721aa467c15451e627dca320f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/271cd33d61c329874bd7397ab893d516e912653fb5aace88b495d7867aae0020d0032b7a32dd4c8a3d69bf4df01179b63696b3b02a32f2360c4efb6108b48467", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b98e45aa88ff2a821e4ad8d50d52fe611964f19ccc314f8000cd090251fad6cc983f3fa55b2187c0ecec64e4196b8e2f83259e5a23b4c5d0803d7513752241ed", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3c57f7956541fe969d9909e3b46b708d2b5a3d2ef5637a82a26c6cd4a4ab00d272bc6d170ddcaf3d9f5c0ed539be1eea684774dcfa9964c3ada4db74c8795e39"} build_url:https://queue.taskcluster.net/v1/task/Q3tBUc7hTPO9FVhiFORHuQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011348 build_url: 'https://queue.taskcluster.net/v1/task/Q3tBUc7hTPO9FVhiFORHuQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/baca659f8dcf07952f87bdd783dfcd3bd8592e5b56521f4b2b2da42570bfea996f3a8a7eb1dff759bbc9d523f14456f92100887721aa467c15451e627dca320f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/271cd33d61c329874bd7397ab893d516e912653fb5aace88b495d7867aae0020d0032b7a32dd4c8a3d69bf4df01179b63696b3b02a32f2360c4efb6108b48467", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b98e45aa88ff2a821e4ad8d50d52fe611964f19ccc314f8000cd090251fad6cc983f3fa55b2187c0ecec64e4196b8e2f83259e5a23b4c5d0803d7513752241ed", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3c57f7956541fe969d9909e3b46b708d2b5a3d2ef5637a82a26c6cd4a4ab00d272bc6d170ddcaf3d9f5c0ed539be1eea684774dcfa9964c3ada4db74c8795e39"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-31 12:03:59.078013) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-31 12:03:59.078342) ========= 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=41a7e32711b8e08598a63237000001d6-1483212920.537035-964079285 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004506 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-31 12:03:59.160504) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-31 12:03:59.160856) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-31 12:03:59.161176) ========= ========= Total master_lag: 0.31 =========