builder: mozilla-central_ubuntu64_hw_stylo_test-dromaeojs slave: talos-linux64-ix-081 starttime: 1494021034.2 results: success (0) revision: ff83fde8be946eabcf27ea97d4676f601c122194 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-05 14:50:34.200272) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-05 14:50:34.200734) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-05 14:50:34.201021) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-05 14:50:34.213108) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 14:50:34.213458) ========= 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=483e410453545d07cca6935c000001ab-1494021034.206561-1957242300 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-05-05 14:50:35-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.2M=0s 2017-05-05 14:50:35 (46.2 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.144833 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 14:50:34.371013) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 14:50:34.371327) ========= 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=483e410453545d07cca6935c000001ab-1494021034.206561-1957242300 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016915 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 14:50:34.420383) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-05 14:50:34.423735) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev ff83fde8be946eabcf27ea97d4676f601c122194 --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 ff83fde8be946eabcf27ea97d4676f601c122194 --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=483e410453545d07cca6935c000001ab-1494021034.206561-1957242300 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-05-05 14:50:35,415 truncating revision to first 12 chars 2017-05-05 14:50:35,415 Setting DEBUG logging. 2017-05-05 14:50:35,415 attempt 1/10 2017-05-05 14:50:35,415 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/ff83fde8be94?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-05 14:50:36,217 unpacking tar archive at: mozilla-central-ff83fde8be94/testing/mozharness/ program finished with exit code 0 elapsedTime=1.098857 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-05 14:50:35.535916) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-05 14:50:35.536239) ========= script_repo_revision: ff83fde8be946eabcf27ea97d4676f601c122194 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-05 14:50:35.536601) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-05 14:50:35.536885) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-05 14:50:35.554153) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2017-05-05 14:50:35.554433) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=483e410453545d07cca6935c000001ab-1494021034.206561-1957242300 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:50:36 INFO - MultiFileLogger online at 20170505 14:50:36 in /builds/slave/test 14:50:36 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 14:50:36 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:50:36 INFO - {'append_to_log': False, 14:50:36 INFO - 'base_work_dir': '/builds/slave/test', 14:50:36 INFO - 'blob_upload_branch': 'Firefox', 14:50:36 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:50:36 INFO - 'branch': 'Firefox', 14:50:36 INFO - 'buildbot_json_path': 'buildprops.json', 14:50:36 INFO - 'config_files': ('talos/linux_config.py',), 14:50:36 INFO - 'default_actions': ('clobber', 14:50:36 INFO - 'read-buildbot-config', 14:50:36 INFO - 'download-and-extract', 14:50:36 INFO - 'populate-webroot', 14:50:36 INFO - 'create-virtualenv', 14:50:36 INFO - 'install', 14:50:36 INFO - 'run-tests'), 14:50:36 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:50:36 INFO - 'download_minidump_stackwalk': True, 14:50:36 INFO - 'download_symbols': 'ondemand', 14:50:36 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:50:36 INFO - 'tooltool.py': '/tools/tooltool.py', 14:50:36 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:50:36 INFO - '/tools/misc-python/virtualenv.py')}, 14:50:36 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:50:36 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:50:36 INFO - 'gecko_profile': False, 14:50:36 INFO - 'gecko_profile_interval': 0, 14:50:36 INFO - 'installer_path': 'installer.exe', 14:50:36 INFO - 'log_level': 'info', 14:50:36 INFO - 'log_name': 'talos', 14:50:36 INFO - 'log_to_console': True, 14:50:36 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:50:36 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:50:36 INFO - 'opt_config_files': (), 14:50:36 INFO - 'pip_index': False, 14:50:36 INFO - 'suite': 'dromaeojs', 14:50:36 INFO - 'system_bits': '32', 14:50:36 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:50:36 INFO - 'title': 'talos-linux64-ix-081', 14:50:36 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:50:36 INFO - 'use_talos_json': True, 14:50:36 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:50:36 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:50:36 INFO - 'work_dir': 'build'} 14:50:36 INFO - [mozharness: 2017-05-05 21:50:36.643280Z] Running clobber step. 14:50:36 INFO - Running pre-action listener: _resource_record_pre_action 14:50:36 INFO - Running main action method: clobber 14:50:36 INFO - rmtree: /builds/slave/test/build 14:50:36 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:50:37 INFO - Running post-action listener: _resource_record_post_action 14:50:37 INFO - [mozharness: 2017-05-05 21:50:37.572889Z] Finished clobber step (success) 14:50:37 INFO - [mozharness: 2017-05-05 21:50:37.572990Z] Running read-buildbot-config step. 14:50:37 INFO - Running pre-action listener: _resource_record_pre_action 14:50:37 INFO - Running main action method: read_buildbot_config 14:50:37 INFO - Using buildbot properties: 14:50:37 INFO - { 14:50:37 INFO - "project": "", 14:50:37 INFO - "product": "firefox", 14:50:37 INFO - "who": "kwierso@gmail.com", 14:50:37 INFO - "installer_path": "public/build/target.tar.bz2", 14:50:37 INFO - "builddir": "mozilla-central_ubuntu64_hw_stylo_test-dromaeojs", 14:50:37 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 14:50:37 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo mozilla-central talos dromaeojs", 14:50:37 INFO - "stage_platform": "linux64-stylo", 14:50:37 INFO - "basedir": "/builds/slave/test", 14:50:37 INFO - "buildnumber": 80, 14:50:37 INFO - "platform": "ubuntu64_hw_stylo", 14:50:37 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 14:50:37 INFO - "slavebuilddir": "test", 14:50:37 INFO - "taskId": "KFGyptsSQPeymtZlh-Qjlg", 14:50:37 INFO - "branch": "mozilla-central", 14:50:37 INFO - "script_repo_revision": "production", 14:50:37 INFO - "revision": "ff83fde8be946eabcf27ea97d4676f601c122194", 14:50:37 INFO - "slavename": "talos-linux64-ix-081", 14:50:37 INFO - "repo_path": "mozilla-central" 14:50:37 INFO - } 14:50:37 INFO - Finding installer, test and symbols from parent task. 14:50:37 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KFGyptsSQPeymtZlh-Qjlg'}, attempt #1 14:50:38 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KFGyptsSQPeymtZlh-Qjlg'}, attempt #1 14:50:38 INFO - Task dependencies: U-qO4zg9Tp-Ne7DNaSe-AA 14:50:38 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/U-qO4zg9Tp-Ne7DNaSe-AA'}, attempt #1 14:50:38 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/U-qO4zg9Tp-Ne7DNaSe-AA/artifacts/public/build/target.tar.bz2 14:50:38 INFO - Set test_url: https://queue.taskcluster.net/v1/task/U-qO4zg9Tp-Ne7DNaSe-AA/artifacts/public/build/test_packages.json 14:50:38 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/U-qO4zg9Tp-Ne7DNaSe-AA/artifacts/public/build/target.crashreporter-symbols.zip 14:50:38 INFO - Running post-action listener: _resource_record_post_action 14:50:38 INFO - [mozharness: 2017-05-05 21:50:38.935343Z] Finished read-buildbot-config step (success) 14:50:38 INFO - [mozharness: 2017-05-05 21:50:38.935560Z] Running download-and-extract step. 14:50:38 INFO - Running pre-action listener: _resource_record_pre_action 14:50:38 INFO - Running main action method: download_and_extract 14:50:38 INFO - mkdir: /builds/slave/test/build/tests 14:50:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:50:38 INFO - trying https://queue.taskcluster.net/v1/task/U-qO4zg9Tp-Ne7DNaSe-AA/artifacts/public/build/target.test_packages.json 14:50:38 INFO - Downloading https://queue.taskcluster.net/v1/task/U-qO4zg9Tp-Ne7DNaSe-AA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 14:50:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/U-qO4zg9Tp-Ne7DNaSe-AA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 14:50:39 INFO - Downloaded 986 bytes. 14:50:39 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 14:50:39 INFO - Using the following test package requirements: 14:50:39 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 14:50:39 INFO - u'common': [u'target.common.tests.zip'], 14:50:39 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 14:50:39 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 14:50:39 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 14:50:39 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 14:50:39 INFO - u'mozbase': [u'target.common.tests.zip'], 14:50:39 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 14:50:39 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 14:50:39 INFO - u'web-platform': [u'target.common.tests.zip', 14:50:39 INFO - u'target.web-platform.tests.zip'], 14:50:39 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 14:50:39 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 14:50:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/U-qO4zg9Tp-Ne7DNaSe-AA/artifacts/public/build/target.common.tests.zip 14:50:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/U-qO4zg9Tp-Ne7DNaSe-AA/artifacts/public/build/target.common.tests.zip'}, attempt #1 14:50:39 INFO - Fetch https://queue.taskcluster.net/v1/task/U-qO4zg9Tp-Ne7DNaSe-AA/artifacts/public/build/target.common.tests.zip into memory 14:50:40 INFO - Content-Length response header: 58443400 14:50:40 INFO - Bytes received: 58443400 14:50:50 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 14:50:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/U-qO4zg9Tp-Ne7DNaSe-AA/artifacts/public/build/target.common.tests.zip 14:50:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/U-qO4zg9Tp-Ne7DNaSe-AA/artifacts/public/build/target.common.tests.zip'}, attempt #1 14:50:50 INFO - Fetch https://queue.taskcluster.net/v1/task/U-qO4zg9Tp-Ne7DNaSe-AA/artifacts/public/build/target.common.tests.zip into memory 14:50:51 INFO - Content-Length response header: 58443400 14:50:51 INFO - Bytes received: 58443400 14:51:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/U-qO4zg9Tp-Ne7DNaSe-AA/artifacts/public/build/target.talos.tests.zip 14:51:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/U-qO4zg9Tp-Ne7DNaSe-AA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 14:51:02 INFO - Fetch https://queue.taskcluster.net/v1/task/U-qO4zg9Tp-Ne7DNaSe-AA/artifacts/public/build/target.talos.tests.zip into memory 14:51:03 INFO - Content-Length response header: 14408697 14:51:03 INFO - Bytes received: 14408697 14:51:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:51:03 INFO - trying https://queue.taskcluster.net/v1/task/U-qO4zg9Tp-Ne7DNaSe-AA/artifacts/public/build/target.tar.bz2 14:51:03 INFO - Downloading https://queue.taskcluster.net/v1/task/U-qO4zg9Tp-Ne7DNaSe-AA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 14:51:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/U-qO4zg9Tp-Ne7DNaSe-AA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 14:51:04 INFO - Downloaded 59909657 bytes. 14:51:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/U-qO4zg9Tp-Ne7DNaSe-AA/artifacts/public/build/target.tar.bz2 14:51:04 INFO - mkdir: /builds/slave/test/properties 14:51:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:51:04 INFO - Writing to file /builds/slave/test/properties/build_url 14:51:04 INFO - Contents: 14:51:04 INFO - build_url:https://queue.taskcluster.net/v1/task/U-qO4zg9Tp-Ne7DNaSe-AA/artifacts/public/build/target.tar.bz2 14:51:04 INFO - Running post-action listener: _resource_record_post_action 14:51:04 INFO - Running post-action listener: set_extra_try_arguments 14:51:04 INFO - [mozharness: 2017-05-05 21:51:04.476514Z] Finished download-and-extract step (success) 14:51:04 INFO - [mozharness: 2017-05-05 21:51:04.476610Z] Running populate-webroot step. 14:51:04 INFO - Running pre-action listener: _resource_record_pre_action 14:51:04 INFO - Running main action method: populate_webroot 14:51:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:51:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:51:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:51:04 INFO - '--tppagecycles', 14:51:04 INFO - '7'], 14:51:04 INFO - 'tests': ['tsvgm']}}, 14:51:04 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:51:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:51:04 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:51:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:51:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:51:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:51:04 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 14:51:04 INFO - 'talos_options': ['--disable-e10s'], 14:51:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:51:04 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:51:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:51:04 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 14:51:04 INFO - 'talos_options': ['--disable-e10s'], 14:51:04 INFO - 'tests': ['damp', 'tps']}, 14:51:04 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:51:04 INFO - 'tests': ['damp', 'tps']}, 14:51:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:51:04 INFO - 'tests': ['dromaeo_dom']}, 14:51:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:51:04 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:51:04 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 14:51:04 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 14:51:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:51:04 INFO - 'tests': ['a11yr', 14:51:04 INFO - 'ts_paint', 14:51:04 INFO - 'tpaint', 14:51:04 INFO - 'sessionrestore', 14:51:04 INFO - 'sessionrestore_no_auto_restore', 14:51:04 INFO - 'tabpaint']}, 14:51:04 INFO - 'other-e10s': {'tests': ['a11yr', 14:51:04 INFO - 'ts_paint', 14:51:04 INFO - 'tpaint', 14:51:04 INFO - 'sessionrestore', 14:51:04 INFO - 'sessionrestore_no_auto_restore', 14:51:04 INFO - 'tabpaint']}, 14:51:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:51:04 INFO - 'ts_paint', 14:51:04 INFO - 'tpaint', 14:51:04 INFO - 'sessionrestore', 14:51:04 INFO - 'sessionrestore_no_auto_restore', 14:51:04 INFO - 'tabpaint']}, 14:51:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:51:04 INFO - 'ts_paint', 14:51:04 INFO - 'tpaint', 14:51:04 INFO - 'sessionrestore', 14:51:04 INFO - 'sessionrestore_no_auto_restore', 14:51:04 INFO - 'tabpaint']}, 14:51:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:51:04 INFO - 'tests': ['a11yr', 14:51:04 INFO - 'ts_paint', 14:51:04 INFO - 'tpaint', 14:51:04 INFO - 'sessionrestore', 14:51:04 INFO - 'sessionrestore_no_auto_restore', 14:51:04 INFO - 'tabpaint']}, 14:51:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:51:04 INFO - 'tests': ['a11yr', 14:51:04 INFO - 'ts_paint', 14:51:04 INFO - 'tpaint', 14:51:04 INFO - 'sessionrestore', 14:51:04 INFO - 'sessionrestore_no_auto_restore', 14:51:04 INFO - 'tabpaint']}, 14:51:04 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 14:51:04 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 14:51:04 INFO - 'bloom_basic_ref']}, 14:51:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:51:04 INFO - 'tests': ['tsvgx', 14:51:04 INFO - 'tsvgr_opacity', 14:51:04 INFO - 'tart', 14:51:04 INFO - 'tscrollx', 14:51:04 INFO - 'cart', 14:51:04 INFO - 'tsvg_static']}, 14:51:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:51:04 INFO - 'tsvgr_opacity', 14:51:04 INFO - 'tart', 14:51:04 INFO - 'tscrollx', 14:51:04 INFO - 'cart', 14:51:04 INFO - 'tsvg_static']}, 14:51:04 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 14:51:04 INFO - 'talos_options': ['--disable-e10s'], 14:51:04 INFO - 'tests': ['tp5o']}, 14:51:04 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:51:04 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 14:51:04 INFO - 'talos_options': ['--disable-e10s', 14:51:04 INFO - '--xperf_path', 14:51:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:51:04 INFO - 'tests': ['tp5n']}, 14:51:04 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:51:04 INFO - 'talos_options': ['--xperf_path', 14:51:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:51:04 INFO - 'tests': ['tp5n']}}, 14:51:04 INFO - 'talos.zip': {'path': '', 14:51:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:51:04 INFO - Running post-action listener: _resource_record_post_action 14:51:04 INFO - [mozharness: 2017-05-05 21:51:04.490946Z] Finished populate-webroot step (success) 14:51:04 INFO - [mozharness: 2017-05-05 21:51:04.491084Z] Running create-virtualenv step. 14:51:04 INFO - Running pre-action listener: _resource_record_pre_action 14:51:04 INFO - Running main action method: create_virtualenv 14:51:04 INFO - Creating virtualenv /builds/slave/test/build/venv 14:51:04 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 14:51:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:51:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:51:04 INFO - Using real prefix '/usr' 14:51:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:51:05 INFO - Installing distribute.............................................................................................................................................................................................done. 14:51:07 INFO - Installing pip.................done. 14:51:07 INFO - Return code: 0 14:51:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:51:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:51:07 INFO - Reading from file tmpfile_stdout 14:51:07 INFO - Output received: 14:51:07 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) 14:51:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:51: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')]} 14:51:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:51:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:51: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')]} 14:51:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:51:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:51:07 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 0x22f41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2331a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2592310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x258f400>, '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 0x25903b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2590840>, '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': '483e410453545d07cca6935c000001ab-1494021034.206561-1957242300', '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 14:51:07 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 14:51:07 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 14:51:07 INFO - Using env: {'DISPLAY': ':0', 14:51:07 INFO - 'HOME': '/home/cltbld', 14:51:07 INFO - 'LANG': 'en_US.UTF-8', 14:51:07 INFO - 'LANGUAGE': 'en_US:en', 14:51:07 INFO - 'LOGNAME': 'cltbld', 14:51:07 INFO - 'MAIL': '/var/mail/cltbld', 14:51:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:51:07 INFO - 'MOZ_NO_REMOTE': '1', 14:51:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:51:07 INFO - 'NO_EM_RESTART': '1', 14:51:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:51:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:51:07 INFO - 'PWD': '/builds/slave/test', 14:51:07 INFO - 'SHELL': '/bin/bash', 14:51:07 INFO - 'SHLVL': '1', 14:51:07 INFO - 'TERM': 'linux', 14:51:07 INFO - 'TMOUT': '86400', 14:51:07 INFO - 'USER': 'cltbld', 14:51:07 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1494021034.206561-1957242300', 14:51:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:51:07 INFO - '_': '/tools/buildbot/bin/python'} 14:51:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:51:07 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 14:51:07 INFO - Cleaning up... 14:51:07 INFO - Return code: 0 14:51:07 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:51: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')]} 14:51:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:51:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:51: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')]} 14:51:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:51:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:51:07 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 0x22f41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2331a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2592310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x258f400>, '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 0x25903b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2590840>, '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': '483e410453545d07cca6935c000001ab-1494021034.206561-1957242300', '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 14:51:07 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 14:51:07 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 14:51:07 INFO - Using env: {'DISPLAY': ':0', 14:51:07 INFO - 'HOME': '/home/cltbld', 14:51:07 INFO - 'LANG': 'en_US.UTF-8', 14:51:07 INFO - 'LANGUAGE': 'en_US:en', 14:51:07 INFO - 'LOGNAME': 'cltbld', 14:51:07 INFO - 'MAIL': '/var/mail/cltbld', 14:51:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:51:07 INFO - 'MOZ_NO_REMOTE': '1', 14:51:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:51:07 INFO - 'NO_EM_RESTART': '1', 14:51:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:51:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:51:07 INFO - 'PWD': '/builds/slave/test', 14:51:07 INFO - 'SHELL': '/bin/bash', 14:51:07 INFO - 'SHLVL': '1', 14:51:07 INFO - 'TERM': 'linux', 14:51:07 INFO - 'TMOUT': '86400', 14:51:07 INFO - 'USER': 'cltbld', 14:51:07 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1494021034.206561-1957242300', 14:51:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:51:07 INFO - '_': '/tools/buildbot/bin/python'} 14:51:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:51:07 INFO - Downloading/unpacking psutil>=3.1.1 14:51:07 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 14:51:07 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 14:51:07 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 14:51:07 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 14:51:07 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 14:51:07 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 14:51:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:51:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:51:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:51:08 INFO - Installing collected packages: psutil 14:51:08 INFO - Running setup.py install for psutil 14:51:08 INFO - building 'psutil._psutil_linux' extension 14:51:08 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 14:51:09 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 14:51:09 INFO - building 'psutil._psutil_posix' extension 14:51:09 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 14:51:09 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 14:51:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:51:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:51:09 INFO - Successfully installed psutil 14:51:09 INFO - Cleaning up... 14:51:09 INFO - Return code: 0 14:51:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:51:09 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')]} 14:51:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:51:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:51:09 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')]} 14:51:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:51:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:51:09 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 0x22f41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2331a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2592310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x258f400>, '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 0x25903b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2590840>, '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': '483e410453545d07cca6935c000001ab-1494021034.206561-1957242300', '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 14:51:09 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 14:51:09 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 14:51:09 INFO - Using env: {'DISPLAY': ':0', 14:51:09 INFO - 'HOME': '/home/cltbld', 14:51:09 INFO - 'LANG': 'en_US.UTF-8', 14:51:09 INFO - 'LANGUAGE': 'en_US:en', 14:51:09 INFO - 'LOGNAME': 'cltbld', 14:51:09 INFO - 'MAIL': '/var/mail/cltbld', 14:51:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:51:09 INFO - 'MOZ_NO_REMOTE': '1', 14:51:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:51:09 INFO - 'NO_EM_RESTART': '1', 14:51:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:51:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:51:09 INFO - 'PWD': '/builds/slave/test', 14:51:09 INFO - 'SHELL': '/bin/bash', 14:51:09 INFO - 'SHLVL': '1', 14:51:09 INFO - 'TERM': 'linux', 14:51:09 INFO - 'TMOUT': '86400', 14:51:09 INFO - 'USER': 'cltbld', 14:51:09 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1494021034.206561-1957242300', 14:51:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:51:09 INFO - '_': '/tools/buildbot/bin/python'} 14:51:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:51:09 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:51:09 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 14:51:09 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 14:51:09 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 14:51:09 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 14:51:09 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 14:51:09 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 14:51:10 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:51:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:51:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:51:10 INFO - Installing collected packages: mozsystemmonitor 14:51:10 INFO - Running setup.py install for mozsystemmonitor 14:51:10 INFO - Successfully installed mozsystemmonitor 14:51:10 INFO - Cleaning up... 14:51:10 INFO - Return code: 0 14:51:10 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:51:10 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')]} 14:51:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:51:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:51:10 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')]} 14:51:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:51:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:51:10 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 0x22f41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2331a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2592310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x258f400>, '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 0x25903b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2590840>, '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': '483e410453545d07cca6935c000001ab-1494021034.206561-1957242300', '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 14:51:10 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 14:51:10 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 14:51:10 INFO - Using env: {'DISPLAY': ':0', 14:51:10 INFO - 'HOME': '/home/cltbld', 14:51:10 INFO - 'LANG': 'en_US.UTF-8', 14:51:10 INFO - 'LANGUAGE': 'en_US:en', 14:51:10 INFO - 'LOGNAME': 'cltbld', 14:51:10 INFO - 'MAIL': '/var/mail/cltbld', 14:51:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:51:10 INFO - 'MOZ_NO_REMOTE': '1', 14:51:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:51:10 INFO - 'NO_EM_RESTART': '1', 14:51:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:51:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:51:10 INFO - 'PWD': '/builds/slave/test', 14:51:10 INFO - 'SHELL': '/bin/bash', 14:51:10 INFO - 'SHLVL': '1', 14:51:10 INFO - 'TERM': 'linux', 14:51:10 INFO - 'TMOUT': '86400', 14:51:10 INFO - 'USER': 'cltbld', 14:51:10 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1494021034.206561-1957242300', 14:51:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:51:10 INFO - '_': '/tools/buildbot/bin/python'} 14:51:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:51:11 INFO - Downloading/unpacking jsonschema==2.5.1 14:51:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:51:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:51:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:51:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:51:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:51:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:51:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:51:12 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:51:12 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 14:51:12 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 14:51:12 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 14:51:12 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 14:51:12 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 14:51:12 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 14:51:12 INFO - Downloading functools32-3.2.3-2.tar.gz 14:51:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:51:12 INFO - warning: no files found matching '*.txt' 14:51:12 INFO - no previously-included directories found matching 'build' 14:51:12 INFO - no previously-included directories found matching 'dist' 14:51:12 INFO - no previously-included directories found matching '.git*' 14:51:12 INFO - Installing collected packages: jsonschema, functools32 14:51:12 INFO - Running setup.py install for functools32 14:51:12 INFO - warning: no files found matching '*.txt' 14:51:12 INFO - no previously-included directories found matching 'build' 14:51:12 INFO - no previously-included directories found matching 'dist' 14:51:12 INFO - no previously-included directories found matching '.git*' 14:51:12 INFO - Successfully installed jsonschema functools32 14:51:12 INFO - Cleaning up... 14:51:12 INFO - Return code: 0 14:51:12 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:51:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:51:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:51:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:51:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:51:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:51:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:51:12 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 0x22f41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2331a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2592310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x258f400>, '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 0x25903b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2590840>, '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': '483e410453545d07cca6935c000001ab-1494021034.206561-1957242300', '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 14:51:12 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 14:51:12 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 14:51:12 INFO - Using env: {'DISPLAY': ':0', 14:51:12 INFO - 'HOME': '/home/cltbld', 14:51:12 INFO - 'LANG': 'en_US.UTF-8', 14:51:12 INFO - 'LANGUAGE': 'en_US:en', 14:51:12 INFO - 'LOGNAME': 'cltbld', 14:51:12 INFO - 'MAIL': '/var/mail/cltbld', 14:51:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:51:12 INFO - 'MOZ_NO_REMOTE': '1', 14:51:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:51:12 INFO - 'NO_EM_RESTART': '1', 14:51:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:51:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:51:12 INFO - 'PWD': '/builds/slave/test', 14:51:12 INFO - 'SHELL': '/bin/bash', 14:51:12 INFO - 'SHLVL': '1', 14:51:12 INFO - 'TERM': 'linux', 14:51:12 INFO - 'TMOUT': '86400', 14:51:12 INFO - 'USER': 'cltbld', 14:51:12 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1494021034.206561-1957242300', 14:51:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:51:12 INFO - '_': '/tools/buildbot/bin/python'} 14:51:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:51:12 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:51:12 INFO - Cleaning up... 14:51:12 INFO - Return code: 0 14:51:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:51:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:51:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:51:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:51:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:51:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:51:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:51:12 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 0x22f41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2331a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2592310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x258f400>, '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 0x25903b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2590840>, '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': '483e410453545d07cca6935c000001ab-1494021034.206561-1957242300', '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 14:51:12 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 14:51:12 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 14:51:12 INFO - Using env: {'DISPLAY': ':0', 14:51:12 INFO - 'HOME': '/home/cltbld', 14:51:12 INFO - 'LANG': 'en_US.UTF-8', 14:51:12 INFO - 'LANGUAGE': 'en_US:en', 14:51:12 INFO - 'LOGNAME': 'cltbld', 14:51:12 INFO - 'MAIL': '/var/mail/cltbld', 14:51:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:51:12 INFO - 'MOZ_NO_REMOTE': '1', 14:51:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:51:12 INFO - 'NO_EM_RESTART': '1', 14:51:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:51:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:51:12 INFO - 'PWD': '/builds/slave/test', 14:51:12 INFO - 'SHELL': '/bin/bash', 14:51:12 INFO - 'SHLVL': '1', 14:51:12 INFO - 'TERM': 'linux', 14:51:12 INFO - 'TMOUT': '86400', 14:51:12 INFO - 'USER': 'cltbld', 14:51:12 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1494021034.206561-1957242300', 14:51:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:51:12 INFO - '_': '/tools/buildbot/bin/python'} 14:51:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:51:13 INFO - Downloading/unpacking blobuploader==1.2.4 14:51:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:51:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:51:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:51:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:51:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:51:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:51:14 INFO - Downloading blobuploader-1.2.4.tar.gz 14:51:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:51:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:51:14 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 14:51:14 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 14:51:14 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 14:51:14 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 14:51:14 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 14:51:14 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 14:51:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:51:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:51:14 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 14:51:14 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 14:51:14 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 14:51:14 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 14:51:14 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 14:51:14 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 14:51:14 INFO - Downloading docopt-0.6.1.tar.gz 14:51:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:51:14 INFO - Installing collected packages: blobuploader, requests, docopt 14:51:14 INFO - Running setup.py install for blobuploader 14:51:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:51:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:51:14 INFO - Running setup.py install for requests 14:51:15 INFO - Running setup.py install for docopt 14:51:15 INFO - Successfully installed blobuploader requests docopt 14:51:15 INFO - Cleaning up... 14:51:15 INFO - Return code: 0 14:51:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:51:15 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')]} 14:51:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:51:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:51:15 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')]} 14:51:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:51:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:51:15 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 0x22f41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2331a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2592310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x258f400>, '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 0x25903b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2590840>, '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': '483e410453545d07cca6935c000001ab-1494021034.206561-1957242300', '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 14:51:15 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 14:51:15 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 14:51:15 INFO - Using env: {'DISPLAY': ':0', 14:51:15 INFO - 'HOME': '/home/cltbld', 14:51:15 INFO - 'LANG': 'en_US.UTF-8', 14:51:15 INFO - 'LANGUAGE': 'en_US:en', 14:51:15 INFO - 'LOGNAME': 'cltbld', 14:51:15 INFO - 'MAIL': '/var/mail/cltbld', 14:51:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:51:15 INFO - 'MOZ_NO_REMOTE': '1', 14:51:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:51:15 INFO - 'NO_EM_RESTART': '1', 14:51:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:51:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:51:15 INFO - 'PWD': '/builds/slave/test', 14:51:15 INFO - 'SHELL': '/bin/bash', 14:51:15 INFO - 'SHLVL': '1', 14:51:15 INFO - 'TERM': 'linux', 14:51:15 INFO - 'TMOUT': '86400', 14:51:15 INFO - 'USER': 'cltbld', 14:51:15 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1494021034.206561-1957242300', 14:51:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:51:15 INFO - '_': '/tools/buildbot/bin/python'} 14:51:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:51:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:51:15 INFO - Running setup.py (path:/tmp/pip-LksdBC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:51:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:51:15 INFO - Running setup.py (path:/tmp/pip-ZwMe2D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:51:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:51:15 INFO - Running setup.py (path:/tmp/pip-Sqz_2v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:51:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:51:15 INFO - Running setup.py (path:/tmp/pip-66dG5A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:51:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:51:15 INFO - Running setup.py (path:/tmp/pip-86ZZZn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:51:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:51:15 INFO - Running setup.py (path:/tmp/pip-AIFscD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:51:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:51:15 INFO - Running setup.py (path:/tmp/pip-c7e0GL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:51:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:51:16 INFO - Running setup.py (path:/tmp/pip-g_c5fl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:51:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:51:16 INFO - Running setup.py (path:/tmp/pip-qSaty4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:51:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:51:16 INFO - Running setup.py (path:/tmp/pip-rjPL6C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:51:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:51:16 INFO - Running setup.py (path:/tmp/pip-8ksy41-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:51:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:51:16 INFO - Running setup.py (path:/tmp/pip-n7eCZC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:51:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:51:16 INFO - Running setup.py (path:/tmp/pip-2NYAtV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:51:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:51:16 INFO - Running setup.py (path:/tmp/pip-8Gn7aC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:51:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:51:16 INFO - Running setup.py (path:/tmp/pip-mOQXyM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:51:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:51:16 INFO - Running setup.py (path:/tmp/pip-ABapkH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:51:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:51:16 INFO - Running setup.py (path:/tmp/pip-I3PwfX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:51:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:51:16 INFO - Running setup.py install for manifestparser 14:51:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:51:16 INFO - Running setup.py install for mozcrash 14:51:16 INFO - Running setup.py install for mozdebug 14:51:17 INFO - Running setup.py install for mozdevice 14:51:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:51:17 INFO - Running setup.py install for mozfile 14:51:17 INFO - Running setup.py install for mozhttpd 14:51:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:51:17 INFO - Running setup.py install for mozinfo 14:51:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:51:17 INFO - Running setup.py install for mozInstall 14:51:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:51:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:51:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:51:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:51:17 INFO - Running setup.py install for mozleak 14:51:17 INFO - Running setup.py install for mozlog 14:51:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:51:17 INFO - Running setup.py install for moznetwork 14:51:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:51:17 INFO - Running setup.py install for mozprocess 14:51:17 INFO - Running setup.py install for mozprofile 14:51:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:51:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:51:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:51:18 INFO - Running setup.py install for mozrunner 14:51:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:51:18 INFO - Running setup.py install for mozscreenshot 14:51:18 INFO - Running setup.py install for moztest 14:51:18 INFO - Running setup.py install for mozversion 14:51:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:51:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:51:18 INFO - Cleaning up... 14:51:18 INFO - Return code: 0 14:51:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:51:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:51:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:51:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:51:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:51:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:51:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:51:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22f41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2331a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2592310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x258f400>, '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 0x25903b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2590840>, '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': '483e410453545d07cca6935c000001ab-1494021034.206561-1957242300', '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 14:51:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:51:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:51:18 INFO - Using env: {'DISPLAY': ':0', 14:51:18 INFO - 'HOME': '/home/cltbld', 14:51:18 INFO - 'LANG': 'en_US.UTF-8', 14:51:18 INFO - 'LANGUAGE': 'en_US:en', 14:51:18 INFO - 'LOGNAME': 'cltbld', 14:51:18 INFO - 'MAIL': '/var/mail/cltbld', 14:51:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:51:18 INFO - 'MOZ_NO_REMOTE': '1', 14:51:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:51:18 INFO - 'NO_EM_RESTART': '1', 14:51:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:51:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:51:18 INFO - 'PWD': '/builds/slave/test', 14:51:18 INFO - 'SHELL': '/bin/bash', 14:51:18 INFO - 'SHLVL': '1', 14:51:18 INFO - 'TERM': 'linux', 14:51:18 INFO - 'TMOUT': '86400', 14:51:18 INFO - 'USER': 'cltbld', 14:51:18 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1494021034.206561-1957242300', 14:51:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:51:18 INFO - '_': '/tools/buildbot/bin/python'} 14:51:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:51:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:51:18 INFO - Running setup.py (path:/tmp/pip-HQKxjJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:51:18 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 14:51:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:51:18 INFO - Running setup.py (path:/tmp/pip-scnfty-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:51:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:51:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:51:18 INFO - Running setup.py (path:/tmp/pip-KA0TW5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:51:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:51:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:51:18 INFO - Running setup.py (path:/tmp/pip-km2EoM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:51:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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)) 14:51:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:51:19 INFO - Running setup.py (path:/tmp/pip-g4Dxtb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:51:19 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)) 14:51:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:51:19 INFO - Running setup.py (path:/tmp/pip-RQ4TQ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:51:19 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)) 14:51:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:51:19 INFO - Running setup.py (path:/tmp/pip-1LRfav-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:51:19 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)) 14:51:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:51:19 INFO - Running setup.py (path:/tmp/pip-or2TRE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:51:19 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)) 14:51:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:51:19 INFO - Running setup.py (path:/tmp/pip-356olg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:51:19 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)) 14:51:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:51:19 INFO - Running setup.py (path:/tmp/pip-IXQLuQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:51:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:51:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:51:19 INFO - Running setup.py (path:/tmp/pip-an527o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:51:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 14:51:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:51:19 INFO - Running setup.py (path:/tmp/pip-ophWpr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:51:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 14:51:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:51:19 INFO - Running setup.py (path:/tmp/pip-1rOkoi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:51:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 14:51:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:51:19 INFO - Running setup.py (path:/tmp/pip-xk8pX7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:51:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 14:51:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:51:19 INFO - Running setup.py (path:/tmp/pip-IjoWfg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:51:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 14:51:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:51:19 INFO - Running setup.py (path:/tmp/pip-3Pofy0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:51:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 14:51:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:51:19 INFO - Running setup.py (path:/tmp/pip-vjtHsg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:51:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 14:51:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:51:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:51:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:51:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:51:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:51:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:51:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:51:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:51:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:51:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:51:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:51:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:51:22 INFO - Downloading blessings-1.6.tar.gz 14:51:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:51:22 INFO - Installing collected packages: blessings 14:51:22 INFO - Running setup.py install for blessings 14:51:22 INFO - Successfully installed blessings 14:51:22 INFO - Cleaning up... 14:51:22 INFO - Return code: 0 14:51:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:51:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:51:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:51:22 INFO - Reading from file tmpfile_stdout 14:51:22 INFO - Current package versions: 14:51:22 INFO - argparse == 1.2.1 14:51:22 INFO - blessings == 1.6 14:51:22 INFO - blobuploader == 1.2.4 14:51:22 INFO - docopt == 0.6.1 14:51:22 INFO - functools32 == 3.2.3-2 14:51:22 INFO - jsonschema == 2.5.1 14:51:22 INFO - manifestparser == 1.1 14:51:22 INFO - mozInstall == 1.12 14:51:22 INFO - mozcrash == 1.0 14:51:22 INFO - mozdebug == 0.1 14:51:22 INFO - mozdevice == 0.50 14:51:22 INFO - mozfile == 1.2 14:51:22 INFO - mozhttpd == 0.7 14:51:22 INFO - mozinfo == 0.9 14:51:22 INFO - mozleak == 0.1 14:51:22 INFO - mozlog == 3.4 14:51:22 INFO - moznetwork == 0.27 14:51:22 INFO - mozprocess == 0.25 14:51:22 INFO - mozprofile == 0.28 14:51:22 INFO - mozrunner == 6.13 14:51:22 INFO - mozscreenshot == 0.1 14:51:22 INFO - mozsystemmonitor == 0.3 14:51:22 INFO - moztest == 0.8 14:51:22 INFO - mozversion == 1.4 14:51:22 INFO - psutil == 3.1.1 14:51:22 INFO - requests == 1.2.3 14:51:22 INFO - wsgiref == 0.1.2 14:51:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:51:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:51:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:51:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:51:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:51:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:51:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:51:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22f41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2331a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2592310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x258f400>, '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 0x25903b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2590840>, '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': '483e410453545d07cca6935c000001ab-1494021034.206561-1957242300', '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 14:51:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 14:51:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:51:22 INFO - Using env: {'DISPLAY': ':0', 14:51:22 INFO - 'HOME': '/home/cltbld', 14:51:22 INFO - 'LANG': 'en_US.UTF-8', 14:51:22 INFO - 'LANGUAGE': 'en_US:en', 14:51:22 INFO - 'LOGNAME': 'cltbld', 14:51:22 INFO - 'MAIL': '/var/mail/cltbld', 14:51:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:51:22 INFO - 'MOZ_NO_REMOTE': '1', 14:51:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:51:22 INFO - 'NO_EM_RESTART': '1', 14:51:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:51:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:51:22 INFO - 'PWD': '/builds/slave/test', 14:51:22 INFO - 'SHELL': '/bin/bash', 14:51:22 INFO - 'SHLVL': '1', 14:51:22 INFO - 'TERM': 'linux', 14:51:22 INFO - 'TMOUT': '86400', 14:51:22 INFO - 'USER': 'cltbld', 14:51:22 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1494021034.206561-1957242300', 14:51:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:51:22 INFO - '_': '/tools/buildbot/bin/python'} 14:51:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:51:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:51:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 14:51:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 14:51:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:51:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 14:51:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 14:51:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 14:51:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 14:51:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 14:51:22 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:51:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:51:22 INFO - Cleaning up... 14:51:22 INFO - Return code: 0 14:51:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:51:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:51:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:51:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:51:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:51:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:51:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:51:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22f41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2331a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2592310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x258f400>, '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 0x25903b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2590840>, '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': '483e410453545d07cca6935c000001ab-1494021034.206561-1957242300', '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 14:51:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:51:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:51:22 INFO - Using env: {'DISPLAY': ':0', 14:51:22 INFO - 'HOME': '/home/cltbld', 14:51:22 INFO - 'LANG': 'en_US.UTF-8', 14:51:22 INFO - 'LANGUAGE': 'en_US:en', 14:51:22 INFO - 'LOGNAME': 'cltbld', 14:51:22 INFO - 'MAIL': '/var/mail/cltbld', 14:51:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:51:22 INFO - 'MOZ_NO_REMOTE': '1', 14:51:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:51:22 INFO - 'NO_EM_RESTART': '1', 14:51:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:51:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:51:22 INFO - 'PWD': '/builds/slave/test', 14:51:22 INFO - 'SHELL': '/bin/bash', 14:51:22 INFO - 'SHLVL': '1', 14:51:22 INFO - 'TERM': 'linux', 14:51:22 INFO - 'TMOUT': '86400', 14:51:22 INFO - 'USER': 'cltbld', 14:51:22 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1494021034.206561-1957242300', 14:51:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:51:22 INFO - '_': '/tools/buildbot/bin/python'} 14:51:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:51:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:51:22 INFO - Cleaning up... 14:51:22 INFO - Return code: 0 14:51:22 INFO - Running post-action listener: _resource_record_post_action 14:51:22 INFO - Running post-action listener: _start_resource_monitoring 14:51:22 INFO - Starting resource monitoring. 14:51:22 INFO - [mozharness: 2017-05-05 21:51:22.964363Z] Finished create-virtualenv step (success) 14:51:22 INFO - [mozharness: 2017-05-05 21:51:22.964891Z] Running install step. 14:51:22 INFO - Running pre-action listener: _resource_record_pre_action 14:51:22 INFO - Running main action method: install 14:51:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:51:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:51:23 INFO - Reading from file tmpfile_stdout 14:51:23 INFO - Detecting whether we're running mozinstall >=1.0... 14:51:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:51:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:51:23 INFO - Reading from file tmpfile_stdout 14:51:23 INFO - Output received: 14:51:23 INFO - Usage: mozinstall [options] installer 14:51:23 INFO - Options: 14:51:23 INFO - -h, --help show this help message and exit 14:51:23 INFO - -d DEST, --destination=DEST 14:51:23 INFO - Directory to install application into. [default: 14:51:23 INFO - "/builds/slave/test"] 14:51:23 INFO - --app=APP Application being installed. [default: firefox] 14:51:23 INFO - mkdir: /builds/slave/test/build/application 14:51:23 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'] 14:51:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 14:51:38 INFO - Reading from file tmpfile_stdout 14:51:38 INFO - Output received: 14:51:38 INFO - /builds/slave/test/build/application/firefox/firefox 14:51:38 INFO - Running post-action listener: _resource_record_post_action 14:51:38 INFO - [mozharness: 2017-05-05 21:51:38.633056Z] Finished install step (success) 14:51:38 INFO - [mozharness: 2017-05-05 21:51:38.633328Z] Running run-tests step. 14:51:38 INFO - Running pre-action listener: _resource_record_pre_action 14:51:38 INFO - Running main action method: run_tests 14:51:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:51:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:51:38 INFO - Python 2.7.3 14:51:38 INFO - Return code: 0 14:51:38 INFO - grabbing minidump binary from tooltool 14:51:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:51:38 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 0x258f400>, '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 0x25903b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2590840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:51:38 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 14:51:38 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 14:51:38 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 14:51:38 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:51:38 INFO - Return code: 0 14:51:38 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:51:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:51:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:51:38 INFO - ENV: RUST_BACKTRACE is now 1 14:51:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:51:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:51:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/U-qO4zg9Tp-Ne7DNaSe-AA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-081', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 14:51:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/U-qO4zg9Tp-Ne7DNaSe-AA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-081 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 14:51:38 INFO - Using env: {'DISPLAY': ':0', 14:51:38 INFO - 'HOME': '/home/cltbld', 14:51:38 INFO - 'LANG': 'en_US.UTF-8', 14:51:38 INFO - 'LANGUAGE': 'en_US:en', 14:51:38 INFO - 'LOGNAME': 'cltbld', 14:51:38 INFO - 'MAIL': '/var/mail/cltbld', 14:51:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:51:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:51:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:51:38 INFO - 'MOZ_NO_REMOTE': '1', 14:51:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:51:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:51:38 INFO - 'NO_EM_RESTART': '1', 14:51:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:51:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:51:38 INFO - 'PWD': '/builds/slave/test', 14:51:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:51:38 INFO - 'RUST_BACKTRACE': '1', 14:51:38 INFO - 'SHELL': '/bin/bash', 14:51:38 INFO - 'SHLVL': '1', 14:51:38 INFO - 'TERM': 'linux', 14:51:38 INFO - 'TMOUT': '86400', 14:51:38 INFO - 'USER': 'cltbld', 14:51:38 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1494021034.206561-1957242300', 14:51:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:51:38 INFO - '_': '/tools/buildbot/bin/python'} 14:51:38 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/U-qO4zg9Tp-Ne7DNaSe-AA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-081', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 14:51:38 INFO - mozversion application_buildid: 20170505210647 14:51:38 INFO - mozversion application_changeset: ff83fde8be946eabcf27ea97d4676f601c122194 14:51:38 INFO - mozversion application_display_name: Nightly 14:51:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:51:38 INFO - mozversion application_name: Firefox 14:51:38 INFO - mozversion application_remotingname: firefox 14:51:38 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 14:51:38 INFO - mozversion application_vendor: Mozilla 14:51:38 INFO - mozversion application_version: 55.0a1 14:51:38 INFO - mozversion platform_buildid: 20170505210647 14:51:38 INFO - mozversion platform_changeset: ff83fde8be946eabcf27ea97d4676f601c122194 14:51:38 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 14:51:38 INFO - mozversion platform_version: 55.0a1 14:51:38 INFO - using testdate: 1494021098 14:51:38 INFO - actual date: 1494021098 14:51:38 INFO - starting webserver on 'localhost:60665' 14:51:38 INFO - SUITE-START | Running 2 tests 14:51:38 INFO - TEST-START | dromaeo_css 14:51:38 INFO - operating with platform_type : linux_ 14:51:38 INFO - Initialising browser for dromaeo_css test... 14:51:38 INFO - TEST-INFO | started process 30984 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwq_jjF/profile http://localhost:60665/getInfo.html) 14:51:43 INFO - PID 30984 | __metrics Screen width/height:1600/1200 14:51:43 INFO - PID 30984 | colorDepth:24 14:51:43 INFO - PID 30984 | Browser inner width/height: 1024/695 14:51:43 INFO - PID 30984 | __metrics 14:51:44 INFO - PID 30984 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:51:44 INFO - PID 30984 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:51:45 INFO - TEST-INFO | 30984: exit 0 14:51:45 INFO - Browser initialized. 14:51:45 INFO - Running cycle 1/1 for dromaeo_css test... 14:51:45 INFO - TEST-INFO | started process 31088 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwq_jjF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:51:57 INFO - PID 31088 | RSS: Main: 186839040 14:51:57 INFO - PID 31088 | 14:55:03 INFO - PID 31088 | Cycle 1(1): loaded http://localhost:60665/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60665/tests/dromaeo/cssquery-ext.html) 14:55:05 INFO - PID 31088 | RSS: Main: 398819328 14:55:05 INFO - PID 31088 | 14:58:08 INFO - PID 31088 | Cycle 1(1): loaded http://localhost:60665/tests/dromaeo/cssquery-ext.html (next: http://localhost:60665/tests/dromaeo/cssquery-jquery.html) 14:58:09 INFO - PID 31088 | RSS: Main: 258084864 14:58:09 INFO - PID 31088 | 15:01:11 INFO - PID 31088 | Cycle 1(1): loaded http://localhost:60665/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60665/tests/dromaeo/cssquery-mootools.html) 15:01:11 INFO - PID 31088 | RSS: Main: 246792192 15:01:11 INFO - PID 31088 | 15:04:14 INFO - PID 31088 | Cycle 1(1): loaded http://localhost:60665/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60665/tests/dromaeo/cssquery-prototype.html) 15:04:14 INFO - PID 31088 | RSS: Main: 261369856 15:04:14 INFO - PID 31088 | 15:07:18 INFO - PID 31088 | Cycle 1(1): loaded http://localhost:60665/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60665/tests/dromaeo/cssquery-yui.html) 15:07:18 INFO - PID 31088 | RSS: Main: 213520384 15:07:18 INFO - PID 31088 | 15:10:21 INFO - PID 31088 | Cycle 1(1): loaded http://localhost:60665/tests/dromaeo/cssquery-yui.html (next: http://localhost:60665/tests/dromaeo/cssquery-dojo.html) 15:10:21 INFO - PID 31088 | RSS: Main: 310861824 15:10:21 INFO - PID 31088 | 15:10:21 INFO - PID 31088 | __start_tp_report 15:10:21 INFO - PID 31088 | _x_x_mozilla_page_load 15:10:21 INFO - PID 31088 | _x_x_mozilla_page_load_details 15:10:21 INFO - PID 31088 | |i|pagename|runs| 15:10:21 INFO - PID 31088 | |0;dojo.html;2694.3802925327172;2726.9318267043323;2746.8489233092173;2749.958750618741;2773.7226277372265;4938.185199442097;5015.924761128582;5024.723640199788;5087.338645976022;5121.692698438096;5021.27191557224;5027.0699920514735;5031.647784655075;5035.496450354972;5038.168702164138;4474.306482495218;4475.39582156408;4479.910401791963;4540.841070562531;4562;4543.886402839922;4553.544645535453;4617.2612382018715;4621.468531118914;4623.0115276644165;4506.285003111388;4556.84051058213;4587.449506059261;4612.630989520831;4627.676062675614;4046.4739583854207;4526.637868970474;4591.816327346902;4594.081183763227;4636.791344389477;4378.211921854065;4426.889327766799;4428;4433.9556604433865;4489.932651010237;4946.530079642428;4955.801767929279;4956.851294461172;4995.525425084611;5033.1695270280725;5405.5135037846785;5440.156775699773;5457.126859702428;5467.0705979983495;5483.506484416382;5354.999999999961;5434.076207044812;5442.782288708447;5464;5486.341639003305;4952.207646776535;5011.724355160502;5042.697438153723;5061.089003979319;5065.569426598707;5546.362168350611;5549.861253468614;5552.944470555323;5558.416366281563;5564.638298510584;5199.2201169824075;5271.341082364705;5278.498542638443;5282.128448805981;5322.175062865262;5346.4118946915805;5386.91919621206;5443.482869127427;5444.8094316698725;5454.181872719124;5323.893522129536;5358.91961620576;5366.490183432567;5366.6779993200535;5372.8656783579945;5367.543758780547;5375.638002566953;5375.9462405376225;5378.3058336623835;5388;4419.204543182193;4479.529649386767;4488.842890498817;4493.977530112328;4509.255972764523;3904.590018048121;3918.294706952719;3921.5882332355263;3929.842806287774;3942.6057394260342;4563.977180114144;4570.7257564546235;4599.540045995441;4630.21286381316;4630.513796051366;5384.730763461811;5385.542228910586;5388.91916621251;5392.892142157213;5394.460553944653;4564;4579.427571553556;4580.633549316047;4620.3993480847275;4642.9535704643195;4763.3807605011125;4769.475357710649;4821.4696383397795;4833.202521583969;4845.53967373099;5086.338775959176;5097.362829646294;5107.157319042316;5123.410807757155;5156.432792392909;5350.9732451337495;5420.972895135499;5439.102548079522;5455.481729235717;5457.863553411196;5025.120603894377;5062.696238225718;5066.290719299227;5081.8475445736685;5085.64400491962;5256.658317209369;5295.894082118413;5314.468553144655;5362.034833729966;5366.2487251784005;5487.835364939058;5498.9826882026955;5500.669959802424;5505;5505.642133261325;5284.656497327662;5293.258943747801;5323.148296272578;5341.786328546815;5348.358197016383;5320.2817619620855;5324.8136315228785;5329.360476742816;5335.199720042025;5345.599080068932;5249.895002100013;5250.317458730341;5255.264263003106;5263.894722105613;5314.441983591822;5233.607479439133;5243.475652434726;5317.654352467077;5318.202269659582;5322.467753224647;5163.870903227449;5168.53483186515;5203.401608814884;5212.296339994053;5213.582913366846 15:10:21 INFO - PID 31088 | |1;ext.html;748.7379417203978;770.5530792140557;1052.200327750909;1066.9519871605778;1071.5874388360485;25845.35386615333;26627;29936;30664;32444.351112977725;33544.32911341772;34001.48997765035;34039;34069.65930340696;34127.14682132948;6920.965395173017;7041.647917604099;7049;7052.083229180225;7069.5758254504635;5269.235960785683;5435.1032079707;6878.965605171968;7024.754133605325;7137.036500072476;7122.252163522833;7155.3202445767565;7175.856482870339;7179.210286868441;7185.425165986735;7061;7121.608311542837;7126.465515086389;7128.6079265640365;7133.357997780173;5229.424763276036;5401.540869026137;5403.918941215885;7005.894911576331;7072.929270707278;23850.642240366407;28843.56734648982;29717.851410743024;29797;29825.105246842628;3083.1367217179227;3119.610048743907;3122.4223518648896;3127.0931429885304;3127.7654175936896;23922.401939951465;23945.521089578284;23956.041758329648;23977;24026.75973240263;3024.6975302469796;3031.166429231979;3045.710657487535;3052.4658184817567;3065.601471808651;138871;139305.30347348299;139394.3030284842;139395;139450;41369.58630413718;41407.37888931608;41415.1716965659;41440.79279603583;41513.58486415097;35159.4726079109;36515.817420912725;42141;43176.78411607985;44365;11280.32318060919;11478.081753459703;11479.4260286987;11513.827292590619;11550.133739969537;8912.598933048028;8917.643294268157;8949;8968.641254349883;9030.27757779376;12700.87299126997;12708.173968692005;12710.110292279636;12760.617181484571;12860.742785144432;5098.898022039612;5169.327987361619;5173.793048278102;5208;5269.156934890476;890.9910900891037;922.460360688991;936.2603543200931;938.061938061938;959.827231098395;20371.8981405095;21631.242906498195;25247.87376063108;26242.343941401235;26592.734072659026;999.93000489965;1005.3163848582888;1008.6973907827622;1011.4993078426226;1016.4003238089563;11247.381394023407;11949.88050119505;14086.366113524917;14875.33061012257;15011;154157.22921385322;154422;154520;154870;155427.22286388496;40805.38791918124;40970.79514602408;40995.79502102471;41013;41075.7946210273;1240.9379531023412;1276.6042526816532;1282.064093211979;1283.826683397748;1297.1139215246003;22981;23298.534029319657;24037.639435408135;24201.152959646326;24226.878865606264;28956.27609309784;29055.85472072626;29070.854645726635;29080.47470980507;29088.854555727085;134090;134634;134641.3267933654;134765;135219;24647.383815404042;25298.36754081163;27065.45869082568;27919.302017449725;28035.29911752141;34442.48336274908;34483;34507.4823877637;34875.825620871736;34886.65113348834;24750.38124046913;25895.352616184748;26183.869080654476;26439.33901652474;26892.86553567298;26360.968488623286;26398.340041499116;26544;26702;27075.86462067677;28320.71679283263;28498.857505712338;28903.56644650374;28929.276768080967;28966.420671586027;24350;25168.874155629106;25287;25486.23541293764;26259;20921.790782091983;22324.66513002274;22994.770052299264;23155.884220579464;23278.185263515697 15:10:21 INFO - PID 31088 | |2;jquery.html;414.4136047492798;423.10935480812867;426.82713501032083;429.1267271103304;440.37246923134546;16012.43956461524;17504.73742893856;18271.26914923405;18296.542586435346;18314.72527912079;17967.281308747635;18201.81798182018;18478.722819157723;19101.904490477562;19228.903855480738;5589.441055894398;5596.4403559644115;5658.236138121342;5692.0323544997245;5700.800471983482;5549.472800084006;5653.123765816305;5653.830385088454;5653.943460565403;5655.123455864347;5284.762185701653;5507.972460137694;5542.168674698787;5543.805966791163;5621.269234999465;5499.477549632778;5507.118860982224;5558.416366281563;5559.082116669742;5561.165825126223;4862.07620552094;4935.555799977983;4975.203967365204;4978.77595508203;4979.900401991976;21913.890430547905;22608.20871269514;22633.09467621293;22675.659865101872;22916.77083229163;1634.6567220883628;1685.073209734641;1685.6881476926808;1685.924133413987;1686.51934198754;14674.55976320712;14687.33906974178;15195.468158614505;15290.541283761506;15371.308291126927;2081.7814129516332;2138.8609740366983;2144.335256070623;2153.149505945143;2162.28644547299;71740.64129679318;89676.5516172415;93196.94219875686;93632.53183734106;94024;18321.7251741224;18640.34758783436;18874.339398121;18968;19517.707234391495;35125.47311790325;35143.648563514034;35159.824200879346;35372.469412958824;35752.46371304432;9149.405288656217;9228.8615670765;9411.576479058458;9657.53732798146;9710.465924374228;7354.3748781353115;7407.481476296715;7423.554586724814;7633;7660.80847978805;9909.157721593745;10064.245181611408;10078.344907580984;10192.13366863825;10230.23273254509;3864.2464719380014;3890.513685789276;3917.8843424550423;3933.095388060762;4047.5142982841658;767.8656235158936;797.8763291689797;809.9919000810035;816.3102178659142;825.2449009156554;16447.424340148038;16506.504804855875;16528.09095499735;16623.3350665972;17074.65850683004;799.0650938402083;799.7643640575325;817.9550124743078;819.7253919936769;824.1511243419287;8586.914130858611;8641.265492433087;8744.912550874536;9099.226565741987;9216.354855160074;350592.2470387632;355928;365721;365974;366359;70302;74243.25756742472;75177;75721.24278757253;76321;1028.1055481730941;1047.4134484688598;1080.4273734920505;1086.9076128529005;1088.9782204355868;13794.172349659053;14188.21964791905;14190.503332383318;14250.714985700435;14593.124412535282;35142;35578;35707.82146089253;36152.27695446129;36247.45628815622;43816;44378;44391.77804110959;45960.770196148806;46352.30471542997;8731.91268087311;8734.868976965223;8818.823623527622;8926.241269492166;9071.682491112779;13234.602961911158;13583.388747506584;13775.655608609864;13797;14057.22685252321;10113.949430252802;10262.794744105226;10292.588296468057;10626.36241825493;11293.604723834625;10905.563777448813;10997.835032474359;11148.219624626197;11160.162987775788;11350.54597816078;11090;11139.777204456028;11255.66233013011;11590;11649.708757280796;8617.82764344722;8671.956640216758;8782.868256976279;8838.734837954871;9047;10226.59093636276;10400.219983501116;10439.94780026095;10759.085477734166;10790.19073569501 15:10:21 INFO - PID 31088 | |3;mootools.html;1222.4804458105302;1301.772189866773;1315.4672357695133;1315.9276239806807;1319.5579480873907;9038.864417033741;9365.688803567498;9441.669541566047;9443.480608566535;9449.574769135388;8852.645894164241;9062.139096785795;9079.77300567486;9237;9255.305852061088;2028.4016215216548;2060.7939206079423;2071.47177469745;2073.1603700501323;2083;1871.4011516314786;1947.5228569000615;2125.9468513287216;2175.2712841198218;2185.9234926777567;2125.404886631738;2203.6143674856917;2208.2712704807454;2210.801027907488;2212.8119109875597;2219.411855858199;2237.19460994042;2248.5053288276717;2248.718910136233;2252.2342403582697;2147.5168087180414;2149.151085321305;2150.397888591197;2154.827613790893;2164.6969424280614;9055.954720226422;9223;9233.676821311223;9247.907520924771;9267.814643707088;5131.897362052738;5285.603579731535;5309.230161626542;5311.840644780624;5324.201369794523;3000.6699263081046;3091.938161236763;3341.2649217172184;3354.7316214702764;3358.932821343584;1057.5089124334652;1069.946502674863;1094.6442406217932;1124.9943750281277;1128.3793913347627;13823.51617693386;13890.097143685629;13891.097078689852;13904.791428128587;13949.930250348683;4279.679024073144;4424.3142312941545;4601.28680054592;4629.537046295411;4655.604273636779;10182.592696292213;10193.898061019443;10202.030807073465;10207.897921020694;10213.285070045025;5101.20931255656;5103.413107492686;5106.923396149061;5114.15616423286;5117.155669314521;4555.088982203506;4689.577937985529;4802.783874725646;4939.357883475125;4953.405591328991;3496.42309019009;3584.444411116281;3663.395539735967;3669.4495825625836;3727.7763334200035;987.9110880020833;1064.6593090211059;1075.198976762317;1085.7122862441458;1099.2250463423268;597.489146779506;650.6909218121355;691.512483698991;730.18219594055;740.0638192686256;5143.099957507421;5231.529162375405;5253.500917412916;5325.600579956519;5341.893162136736;814.3607268294361;836.7071524966213;838.3460900497622;848.7962888906618;862.66787286895;3033.135556366425;3060.1125673554866;3064.800787948821;3087.6140482439696;3176.587043684306;11561.479733412016;11961.521539138337;11968.581099661471;11981.700457488632;12032.217905836093;11817.468213930393;11836.408179590986;11846.111541634413;11857.762844743056;11864.762704745857;7063.187733410724;7172.713091476387;7228.1326240850385;7307.3058059484265;7333.963330183422;4999.625028122832;4999.625028122832;5083.440821509705;5205.427402985744;5884.6469211847425;10469.476526173508;10741.409222492526;10938.015578597646;10987.175961802737;11227.38249396291;10952;11061.723456913642;11203.21577489568;11259.437028148724;11263.211575189658;7544.4341674373545;7781.105172905189;7856.09654889695;7859.724909628136;7870.331021862977;8773.122687731176;8823.69117080899;8830.955845220733;8833.823323533621;8835.779105522413;7263.891041634274;7413.147488038944;7419.332260096618;7577.848443031219;7695.384369250559;7316.8902466464115;7318.524295920748;7414.777556673308;7465;7778.611069446391;6714.59712417237;6827.21487028998;6857.5199736018;6876.243613202644;6903.378695917391;4908.312836203006;5015.974920125376;5024.321716568363;5062.594992400525;5127.948720512747;4873.4639189689815;4965.4786247444945;4980.302757613865;4995;5007.098722230034 15:10:21 INFO - PID 31088 | |4;prototype.html;366.70846676891887;379.6981399787169;380.61557826595134;383.28708602000256;383.84837988994366;5285.920711189336;5297.549708274792;5346.572274218063;5356.643077046102;5361.705106219157;4991.825286114987;5194.039102765991;5199.194124910636;5220.608454365928;5243.161094224924;3973.682105431573;3984.063745019918;3985.3224951758125;3988.521377434712;3993.5607083220966;3971.60283971602;3981.3231750602467;3988.8205030773684;3990.1620659661507;4000.3598524604918;3799.25914446683;3937.1338305572726;3954.070793363557;3975.8012099394914;3982.701297402692;3923.2149372035797;3936.940945885814;3940.034691500586;3951;3953.090789118519;3471.8090505022215;3474.339875423687;3477.23500829817;3487.808170550619;3488.982555087234;4876.195427754416;4982.850514484571;5021.573166280834;5024.974875125601;5025.14572522672;4608.248865431115;4643.3963584733765;4645.698029628097;4669.556392142741;4680.157571637104;5464.754086066137;5508.669479831183;5513.228148059235;5515.696636684981;5546;3826.559945606263;3882.611738826123;3886.4753258310047;3887.455756194135;3888.2223555289056;5547;5626.971865140648;5744.82765517031;5765.4522820332;5873.588848780587;3976.661983731415;3983.8605648802154;3988.481497405319;3990.3016972029777;3999.24014437256;3980.8208630611693;4036.59634036594;4045.0089728016824;4046.0896298333228;4061.6750659947133;4405.823767049346;4518.887027824265;4526.099038403879;4591.219492686251;4611.9077618447445;5492.972535137299;5502.257195278667;5508.476694713996;5518.255035570147;5524.640898341595;1366.5695305978586;1378.6484446466086;1388.0977364713017;1389.1317926296065;1390.1033833177544;923.5982347678647;936.0826390137568;938.3853575907791;942.7878727286305;950.7052813627797;4862.343583616166;4867.58625516835;4870.878228044327;4878.585320247747;4889.119958407521;5756.222909907193;5761.164631128463;5769.59612827098;5774.220480235199;5778.971105144447;4701.78841952113;4810.855674329776;4858.5384388483035;4867.537583929521;4868.610511159099;3012.5631783391286;3043.9847800760854;3048.679888611708;3053.1451193665807;3053.6640969493337;5176.430592634807;5369.7310638042845;5380.78476860929;5384.623076384693;5386.892262154735;5836.153757705109;5847.222569279643;5866.941330586639;5874.148248504029;5903;5320.707361095099;5321.441248668912;5345.358556973188;5360.946390536123;5367.812126575629;5520.447955204448;5706.543476521785;5709.286339207599;5712.314522257356;5718.313802343745;5364.195370694427;5553.500184983371;5620.185073164286;5631.352394474688;5645.68948707825;5203.531682148625;5265.157574788169;5350.384705758732;5358.383785864676;5384.730763461889;4352.978235108804;4381.145676593033;4393.0774537347515;4396.670249731219;4404.537523560109;5663.8867222656145;5666.801661941812;5688.971555142198;5745.770169193185;5763.884722305446;5525;5530.419305972815;5533.723313834212;5545.556355491471;5547.805826796042;5510.504054635102;5533.695646739468;5542.140968149944;5556.527695145958;5576.10782274834;5366.490183432567;5454.809081682122;5462.126059830408;5496.509716330239;5506.311711036121;5498.120300751861;5526.171074338881;5557.110862262019;5559.444055594408;5568.777248909998;5266.368035835724;5314.441983591667;5416.7562459688625;5426.5387442066285;5430.864228394322 15:10:21 INFO - PID 31088 | |5;yui.html;736.8305289783353;738.8448425830576;757.3486801350839;760.9771706848793;764.7896828372201;12280.447379867905;13392.129511581741;13399.799003014938;13467.79798303025;13522.864771352284;13312.534061307857;15483.612909677264;16034.198290085478;16175.43385981491;16178.514644560653;903.4127816919006;923.4782347973407;924.1728652855691;925.7685578605349;927.5872236854602;908.7182973278304;910.167197014734;910.6175406329324;911.7173676160412;912.4388501071826;947.3747326764337;955.3980991975046;955.5461155950937;956.7560272130597;956.9377990430635;937.2408349237106;937.723371605378;943.7829299261174;945.2815859946421;945.4280160502866;685.25649670108;692.62944324786;693.5665209244222;695.2838576266454;696.0672698583871;14058.92970535151;14059.437622495088;14200.076994995292;14261.215633140173;14453.349599268058;14363.353649085817;14551.563453096423;14971.475998340005;15008.699826003418;15009.099454032794;11623.593174238946;11820.23168494045;11828.585999490062;11859.229150105215;11867.58463453775;2887.5379939209834;2893.2477555835426;2904.7385735283924;2916.5625156226524;2918.1391489510647;70035;71886.64056679736;72328.27671723321;72680.6365968172;74325.25674743237;12202.572909948109;12220.694482637828;12293.323867187211;12343.382830858422;12358.50565977351;20750.06624701892;20878.37364879056;21293;21299.78700212978;21336.359909202747;5845.4446827552165;5891.49922256613;5894.8821023579285;5906.586538942232;5913.733881975321;2848.3875966667265;2877.3813630069635;2899.9565006524917;2900.9709902901122;2902.7097290271226;3337.332533493311;3412.709919656857;3417.9145521362166;3420.1278673937986;3424.33225521026;628.3967391304399;632.5666918160982;633.4457349818908;634.1597383466925;634.4385219081054;578.8668606220508;593.2850914647839;593.8960681880653;600.4775845014865;602.2231321595181;9116.179543841086;9415.482148481762;9839.950800245953;10131.949340253399;10220.693379198636;560.6103757888228;562.8733534954603;566.0575142387878;570.067938919869;584.1821449970076;4052.3516237401873;4060.573639767841;4064.2684316822665;4081.6122468365456;4093.529244136903;61181.38818611757;61589.69205153945;62360.68819655963;63684.36315636784;65815.34184658188;54454.45545544573;57071.71464142653;57370;57434.42565574291;58056.4194358051;49594.25608615873;49746.502534974185;50490;51206.23190652143;51605.741971290656;8187.385946053951;10589.523471443958;11433;11464.656060318204;11639.5344186235;24521.632175517738;25226.621600675637;25228.621570676823;25840.741592584585;26655.067072652364;42415;43281;44558.57056209083;47308.763456182496;47870.521294788;13134.60596182116;13458.125221860548;13676.31618419095;13715.657108571966;13946.79079813824;15134.470293539673;15176.620584485476;15303.46437874669;15313.234338282818;15481;13660.248686321951;13885.583432497042;14055.297235137998;14212.857871421567;14402.20787856678;13047.412866420844;13135.277559734306;13159.736805264032;13264.336783160996;13474.528391506643;13976.301184940508;14037.929810350883;14085.577432677037;14244.145351278956;14361.138331700131;9473.621055157717;9750.463724495221;9788.559514821707;9794.853077203705;10001.34991225568;9538.475383853955;9639.469829159463;9643.807123857623;9706.417614943126;10061.094501494901 15:10:21 INFO - PID 31088 | __end_tp_report 15:10:21 INFO - PID 31088 | __start_cc_report 15:10:21 INFO - PID 31088 | _x_x_mozilla_cycle_collect,2670 15:10:21 INFO - PID 31088 | __end_cc_report 15:10:21 INFO - PID 31088 | __startTimestamp1494022221325__endTimestamp 15:10:21 INFO - PID 31088 | 15:10:21 INFO - PID 31088 | ------- Summary: start ------- 15:10:21 INFO - PID 31088 | Number of tests: 6 15:10:21 INFO - PID 31088 | 15:10:21 INFO - PID 31088 | [#0] dojo.html Cycles:165 Average:4965.81 Median:5121.69 stddev:564.52 (11.0%) stddev-sans-first:537.39 15:10:21 INFO - PID 31088 | Values: 2694.4 2726.9 2746.8 2750.0 2773.7 4938.2 5015.9 5024.7 5087.3 5121.7 5021.3 5027.1 5031.6 5035.5 5038.2 4474.3 4475.4 4479.9 4540.8 4562.0 4543.9 4553.5 4617.3 4621.5 4623.0 4506.3 4556.8 4587.4 4612.6 4627.7 4046.5 4526.6 4591.8 4594.1 4636.8 4378.2 4426.9 4428.0 4434.0 4489.9 4946.5 4955.8 4956.9 4995.5 5033.2 5405.5 5440.2 5457.1 5467.1 5483.5 5355.0 5434.1 5442.8 5464.0 5486.3 4952.2 5011.7 5042.7 5061.1 5065.6 5546.4 5549.9 5552.9 5558.4 5564.6 5199.2 5271.3 5278.5 5282.1 5322.2 5346.4 5386.9 5443.5 5444.8 5454.2 5323.9 5358.9 5366.5 5366.7 5372.9 5367.5 5375.6 5375.9 5378.3 5388.0 4419.2 4479.5 4488.8 4494.0 4509.3 3904.6 3918.3 3921.6 3929.8 3942.6 4564.0 4570.7 4599.5 4630.2 4630.5 5384.7 5385.5 5388.9 5392.9 5394.5 4564.0 4579.4 4580.6 4620.4 4643.0 4763.4 4769.5 4821.5 4833.2 4845.5 5086.3 5097.4 5107.2 5123.4 5156.4 5351.0 5421.0 5439.1 5455.5 5457.9 5025.1 5062.7 5066.3 5081.8 5085.6 5256.7 5295.9 5314.5 5362.0 5366.2 5487.8 5499.0 5500.7 5505.0 5505.6 5284.7 5293.3 5323.1 5341.8 5348.4 5320.3 5324.8 5329.4 5335.2 5345.6 5249.9 5250.3 5255.3 5263.9 5314.4 5233.6 5243.5 5317.7 5318.2 5322.5 5163.9 5168.5 5203.4 5212.3 5213.6 15:10:21 INFO - PID 31088 | 15:10:21 INFO - PID 31088 | [#1] ext.html Cycles:180 Average:28604.95 Median:28961.35 stddev:36817.15 (127.1%) stddev-sans-first:36861.01 15:10:21 INFO - PID 31088 | Values: 748.7 770.6 1052.2 1067.0 1071.6 25845.4 26627.0 29936.0 30664.0 32444.4 33544.3 34001.5 34039.0 34069.7 34127.1 6921.0 7041.6 7049.0 7052.1 7069.6 5269.2 5435.1 6879.0 7024.8 7137.0 7122.3 7155.3 7175.9 7179.2 7185.4 7061.0 7121.6 7126.5 7128.6 7133.4 5229.4 5401.5 5403.9 7005.9 7072.9 23850.6 28843.6 29717.9 29797.0 29825.1 3083.1 3119.6 3122.4 3127.1 3127.8 23922.4 23945.5 23956.0 23977.0 24026.8 3024.7 3031.2 3045.7 3052.5 3065.6 138871.0 139305.3 139394.3 139395.0 139450.0 41369.6 41407.4 41415.2 41440.8 41513.6 35159.5 36515.8 42141.0 43176.8 44365.0 11280.3 11478.1 11479.4 11513.8 11550.1 8912.6 8917.6 8949.0 8968.6 9030.3 12700.9 12708.2 12710.1 12760.6 12860.7 5098.9 5169.3 5173.8 5208.0 5269.2 891.0 922.5 936.3 938.1 959.8 20371.9 21631.2 25247.9 26242.3 26592.7 999.9 1005.3 1008.7 1011.5 1016.4 11247.4 11949.9 14086.4 14875.3 15011.0 154157.2 154422.0 154520.0 154870.0 155427.2 40805.4 40970.8 40995.8 41013.0 41075.8 1240.9 1276.6 1282.1 1283.8 1297.1 22981.0 23298.5 24037.6 24201.2 24226.9 28956.3 29055.9 29070.9 29080.5 29088.9 134090.0 134634.0 134641.3 134765.0 135219.0 24647.4 25298.4 27065.5 27919.3 28035.3 34442.5 34483.0 34507.5 34875.8 34886.7 24750.4 25895.4 26183.9 26439.3 26892.9 26361.0 26398.3 26544.0 26702.0 27075.9 28320.7 28498.9 28903.6 28929.3 28966.4 24350.0 25168.9 25287.0 25486.2 26259.0 20921.8 22324.7 22994.8 23155.9 23278.2 15:10:21 INFO - PID 31088 | 15:10:21 INFO - PID 31088 | [#2] jquery.html Cycles:180 Average:25338.39 Median:200984.23 stddev:59961.40 (29.8%) stddev-sans-first:60100.41 15:10:21 INFO - PID 31088 | Values: 414.4 423.1 426.8 429.1 440.4 16012.4 17504.7 18271.3 18296.5 18314.7 17967.3 18201.8 18478.7 19101.9 19228.9 5589.4 5596.4 5658.2 5692.0 5700.8 5549.5 5653.1 5653.8 5653.9 5655.1 5284.8 5508.0 5542.2 5543.8 5621.3 5499.5 5507.1 5558.4 5559.1 5561.2 4862.1 4935.6 4975.2 4978.8 4979.9 21913.9 22608.2 22633.1 22675.7 22916.8 1634.7 1685.1 1685.7 1685.9 1686.5 14674.6 14687.3 15195.5 15290.5 15371.3 2081.8 2138.9 2144.3 2153.1 2162.3 71740.6 89676.6 93196.9 93632.5 94024.0 18321.7 18640.3 18874.3 18968.0 19517.7 35125.5 35143.6 35159.8 35372.5 35752.5 9149.4 9228.9 9411.6 9657.5 9710.5 7354.4 7407.5 7423.6 7633.0 7660.8 9909.2 10064.2 10078.3 10192.1 10230.2 3864.2 3890.5 3917.9 3933.1 4047.5 767.9 797.9 810.0 816.3 825.2 16447.4 16506.5 16528.1 16623.3 17074.7 799.1 799.8 818.0 819.7 824.2 8586.9 8641.3 8744.9 9099.2 9216.4 350592.2 355928.0 365721.0 365974.0 366359.0 70302.0 74243.3 75177.0 75721.2 76321.0 1028.1 1047.4 1080.4 1086.9 1089.0 13794.2 14188.2 14190.5 14250.7 14593.1 35142.0 35578.0 35707.8 36152.3 36247.5 43816.0 44378.0 44391.8 45960.8 46352.3 8731.9 8734.9 8818.8 8926.2 9071.7 13234.6 13583.4 13775.7 13797.0 14057.2 10113.9 10262.8 10292.6 10626.4 11293.6 10905.6 10997.8 11148.2 11160.2 11350.5 11090.0 11139.8 11255.7 11590.0 11649.7 8617.8 8672.0 8782.9 8838.7 9047.0 10226.6 10400.2 10439.9 10759.1 10790.2 15:10:21 INFO - PID 31088 | 15:10:21 INFO - PID 31088 | [#3] mootools.html Cycles:180 Average:5802.83 Median:4746.18 stddev:3649.33 (76.9%) stddev-sans-first:3643.34 15:10:21 INFO - PID 31088 | Values: 1222.5 1301.8 1315.5 1315.9 1319.6 9038.9 9365.7 9441.7 9443.5 9449.6 8852.6 9062.1 9079.8 9237.0 9255.3 2028.4 2060.8 2071.5 2073.2 2083.0 1871.4 1947.5 2125.9 2175.3 2185.9 2125.4 2203.6 2208.3 2210.8 2212.8 2219.4 2237.2 2248.5 2248.7 2252.2 2147.5 2149.2 2150.4 2154.8 2164.7 9056.0 9223.0 9233.7 9247.9 9267.8 5131.9 5285.6 5309.2 5311.8 5324.2 3000.7 3091.9 3341.3 3354.7 3358.9 1057.5 1069.9 1094.6 1125.0 1128.4 13823.5 13890.1 13891.1 13904.8 13949.9 4279.7 4424.3 4601.3 4629.5 4655.6 10182.6 10193.9 10202.0 10207.9 10213.3 5101.2 5103.4 5106.9 5114.2 5117.2 4555.1 4689.6 4802.8 4939.4 4953.4 3496.4 3584.4 3663.4 3669.4 3727.8 987.9 1064.7 1075.2 1085.7 1099.2 597.5 650.7 691.5 730.2 740.1 5143.1 5231.5 5253.5 5325.6 5341.9 814.4 836.7 838.3 848.8 862.7 3033.1 3060.1 3064.8 3087.6 3176.6 11561.5 11961.5 11968.6 11981.7 12032.2 11817.5 11836.4 11846.1 11857.8 11864.8 7063.2 7172.7 7228.1 7307.3 7334.0 4999.6 4999.6 5083.4 5205.4 5884.6 10469.5 10741.4 10938.0 10987.2 11227.4 10952.0 11061.7 11203.2 11259.4 11263.2 7544.4 7781.1 7856.1 7859.7 7870.3 8773.1 8823.7 8831.0 8833.8 8835.8 7263.9 7413.1 7419.3 7577.8 7695.4 7316.9 7318.5 7414.8 7465.0 7778.6 6714.6 6827.2 6857.5 6876.2 6903.4 4908.3 5016.0 5024.3 5062.6 5127.9 4873.5 4965.5 4980.3 4995.0 5007.1 15:10:21 INFO - PID 31088 | 15:10:21 INFO - PID 31088 | [#4] prototype.html Cycles:180 Average:4568.31 Median:5231.88 stddev:1338.07 (25.6%) stddev-sans-first:1304.14 15:10:21 INFO - PID 31088 | Values: 366.7 379.7 380.6 383.3 383.8 5285.9 5297.5 5346.6 5356.6 5361.7 4991.8 5194.0 5199.2 5220.6 5243.2 3973.7 3984.1 3985.3 3988.5 3993.6 3971.6 3981.3 3988.8 3990.2 4000.4 3799.3 3937.1 3954.1 3975.8 3982.7 3923.2 3936.9 3940.0 3951.0 3953.1 3471.8 3474.3 3477.2 3487.8 3489.0 4876.2 4982.9 5021.6 5025.0 5025.1 4608.2 4643.4 4645.7 4669.6 4680.2 5464.8 5508.7 5513.2 5515.7 5546.0 3826.6 3882.6 3886.5 3887.5 3888.2 5547.0 5627.0 5744.8 5765.5 5873.6 3976.7 3983.9 3988.5 3990.3 3999.2 3980.8 4036.6 4045.0 4046.1 4061.7 4405.8 4518.9 4526.1 4591.2 4611.9 5493.0 5502.3 5508.5 5518.3 5524.6 1366.6 1378.6 1388.1 1389.1 1390.1 923.6 936.1 938.4 942.8 950.7 4862.3 4867.6 4870.9 4878.6 4889.1 5756.2 5761.2 5769.6 5774.2 5779.0 4701.8 4810.9 4858.5 4867.5 4868.6 3012.6 3044.0 3048.7 3053.1 3053.7 5176.4 5369.7 5380.8 5384.6 5386.9 5836.2 5847.2 5866.9 5874.1 5903.0 5320.7 5321.4 5345.4 5360.9 5367.8 5520.4 5706.5 5709.3 5712.3 5718.3 5364.2 5553.5 5620.2 5631.4 5645.7 5203.5 5265.2 5350.4 5358.4 5384.7 4353.0 4381.1 4393.1 4396.7 4404.5 5663.9 5666.8 5689.0 5745.8 5763.9 5525.0 5530.4 5533.7 5545.6 5547.8 5510.5 5533.7 5542.1 5556.5 5576.1 5366.5 5454.8 5462.1 5496.5 5506.3 5498.1 5526.2 5557.1 5559.4 5568.8 5266.4 5314.4 5416.8 5426.5 5430.9 15:10:21 INFO - PID 31088 | 15:10:21 INFO - PID 31088 | [#5] yui.html Cycles:180 Average:15340.92 Median:4072.94 stddev:18488.15 (453.9%) stddev-sans-first:18507.49 15:10:21 INFO - PID 31088 | Values: 736.8 738.8 757.3 761.0 764.8 12280.4 13392.1 13399.8 13467.8 13522.9 13312.5 15483.6 16034.2 16175.4 16178.5 903.4 923.5 924.2 925.8 927.6 908.7 910.2 910.6 911.7 912.4 947.4 955.4 955.5 956.8 956.9 937.2 937.7 943.8 945.3 945.4 685.3 692.6 693.6 695.3 696.1 14058.9 14059.4 14200.1 14261.2 14453.3 14363.4 14551.6 14971.5 15008.7 15009.1 11623.6 11820.2 11828.6 11859.2 11867.6 2887.5 2893.2 2904.7 2916.6 2918.1 70035.0 71886.6 72328.3 72680.6 74325.3 12202.6 12220.7 12293.3 12343.4 12358.5 20750.1 20878.4 21293.0 21299.8 21336.4 5845.4 5891.5 5894.9 5906.6 5913.7 2848.4 2877.4 2900.0 2901.0 2902.7 3337.3 3412.7 3417.9 3420.1 3424.3 628.4 632.6 633.4 634.2 634.4 578.9 593.3 593.9 600.5 602.2 9116.2 9415.5 9840.0 10131.9 10220.7 560.6 562.9 566.1 570.1 584.2 4052.4 4060.6 4064.3 4081.6 4093.5 61181.4 61589.7 62360.7 63684.4 65815.3 54454.5 57071.7 57370.0 57434.4 58056.4 49594.3 49746.5 50490.0 51206.2 51605.7 8187.4 10589.5 11433.0 11464.7 11639.5 24521.6 25226.6 25228.6 25840.7 26655.1 42415.0 43281.0 44558.6 47308.8 47870.5 13134.6 13458.1 13676.3 13715.7 13946.8 15134.5 15176.6 15303.5 15313.2 15481.0 13660.2 13885.6 14055.3 14212.9 14402.2 13047.4 13135.3 13159.7 13264.3 13474.5 13976.3 14037.9 14085.6 14244.1 14361.1 9473.6 9750.5 9788.6 9794.9 10001.3 9538.5 9639.5 9643.8 9706.4 10061.1 15:10:21 INFO - PID 31088 | -------- Summary: end -------- 15:10:21 INFO - PID 31088 | 15:10:22 INFO - TEST-INFO | 31088: exit 0 15:10:22 INFO - TEST-OK | dromaeo_css | took 1123341ms 15:10:22 INFO - TEST-START | kraken 15:10:22 INFO - operating with platform_type : linux_ 15:10:22 INFO - Initialising browser for kraken test... 15:10:22 INFO - TEST-INFO | started process 31424 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0oU2Ns/profile http://localhost:60665/getInfo.html) 15:10:26 INFO - PID 31424 | __metrics Screen width/height:1600/1200 15:10:26 INFO - PID 31424 | colorDepth:24 15:10:26 INFO - PID 31424 | Browser inner width/height: 1024/695 15:10:26 INFO - PID 31424 | __metrics 15:10:27 INFO - PID 31424 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:10:27 INFO - PID 31424 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:10:28 INFO - TEST-INFO | 31424: exit 0 15:10:28 INFO - Browser initialized. 15:10:28 INFO - Running cycle 1/1 for kraken test... 15:10:28 INFO - TEST-INFO | started process 31527 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0oU2Ns/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:10:40 INFO - PID 31527 | RSS: Main: 188043264 15:10:40 INFO - PID 31527 | 15:10:43 INFO - PID 31527 | Cycle 1(1): loaded http://localhost:60665/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60665/tests/kraken/driver.html?testName=audio-beat-detection) 15:10:44 INFO - PID 31527 | RSS: Main: 257069056 15:10:44 INFO - PID 31527 | 15:10:47 INFO - PID 31527 | Cycle 1(1): loaded http://localhost:60665/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60665/tests/kraken/driver.html?testName=audio-dft) 15:10:48 INFO - PID 31527 | RSS: Main: 217071616 15:10:48 INFO - PID 31527 | 15:10:51 INFO - PID 31527 | Cycle 1(1): loaded http://localhost:60665/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60665/tests/kraken/driver.html?testName=audio-fft) 15:10:51 INFO - PID 31527 | RSS: Main: 298590208 15:10:51 INFO - PID 31527 | 15:10:54 INFO - PID 31527 | Cycle 1(1): loaded http://localhost:60665/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60665/tests/kraken/driver.html?testName=audio-oscillator) 15:10:54 INFO - PID 31527 | RSS: Main: 213553152 15:10:54 INFO - PID 31527 | 15:10:57 INFO - PID 31527 | Cycle 1(1): loaded http://localhost:60665/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60665/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:10:57 INFO - PID 31527 | RSS: Main: 215613440 15:10:57 INFO - PID 31527 | 15:11:00 INFO - PID 31527 | Cycle 1(1): loaded http://localhost:60665/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60665/tests/kraken/driver.html?testName=imaging-darkroom) 15:11:01 INFO - PID 31527 | RSS: Main: 276672512 15:11:01 INFO - PID 31527 | 15:11:05 INFO - PID 31527 | Cycle 1(1): loaded http://localhost:60665/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60665/tests/kraken/driver.html?testName=imaging-desaturate) 15:11:05 INFO - PID 31527 | RSS: Main: 273440768 15:11:05 INFO - PID 31527 | 15:11:08 INFO - PID 31527 | Cycle 1(1): loaded http://localhost:60665/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60665/tests/kraken/driver.html?testName=json-parse-financial) 15:11:09 INFO - PID 31527 | RSS: Main: 272494592 15:11:09 INFO - PID 31527 | 15:11:10 INFO - PID 31527 | Cycle 1(1): loaded http://localhost:60665/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60665/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:11:11 INFO - PID 31527 | RSS: Main: 236814336 15:11:11 INFO - PID 31527 | 15:11:13 INFO - PID 31527 | Cycle 1(1): loaded http://localhost:60665/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60665/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:11:13 INFO - PID 31527 | RSS: Main: 241410048 15:11:13 INFO - PID 31527 | 15:11:16 INFO - PID 31527 | Cycle 1(1): loaded http://localhost:60665/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60665/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:11:16 INFO - PID 31527 | RSS: Main: 251674624 15:11:16 INFO - PID 31527 | 15:11:19 INFO - PID 31527 | Cycle 1(1): loaded http://localhost:60665/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60665/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:11:20 INFO - PID 31527 | RSS: Main: 258334720 15:11:20 INFO - PID 31527 | 15:11:23 INFO - PID 31527 | Cycle 1(1): loaded http://localhost:60665/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60665/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:11:24 INFO - PID 31527 | RSS: Main: 254889984 15:11:24 INFO - PID 31527 | 15:11:25 INFO - PID 31527 | Cycle 1(1): loaded http://localhost:60665/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60665/tests/kraken/driver.html?testName=ai-astar) 15:11:26 INFO - PID 31527 | RSS: Main: 305967104 15:11:26 INFO - PID 31527 | 15:11:26 INFO - PID 31527 | __start_tp_report 15:11:26 INFO - PID 31527 | _x_x_mozilla_page_load 15:11:26 INFO - PID 31527 | _x_x_mozilla_page_load_details 15:11:26 INFO - PID 31527 | |i|pagename|runs| 15:11:26 INFO - PID 31527 | |0;ai-astar;111;111;111;111;111;112;111;112;112;113 15:11:26 INFO - PID 31527 | |1;audio-beat-detection;153;132;126;165;121;126;127;123;146;122 15:11:26 INFO - PID 31527 | |2;audio-dft;161;164;161;162;163;162;160;161;161;163 15:11:26 INFO - PID 31527 | |3;audio-fft;74;76;73;118;76;75;75;74;95;74 15:11:26 INFO - PID 31527 | |4;audio-oscillator;83;84;104;83;83;83;96;83;84;110 15:11:26 INFO - PID 31527 | |5;imaging-gaussian-blur;113;113;113;113;113;113;113;115;114;113 15:11:26 INFO - PID 31527 | |6;imaging-darkroom;151;149;149;149;149;151;149;150;148;148 15:11:26 INFO - PID 31527 | |7;imaging-desaturate;94;94;94;93;93;95;94;93;93;93 15:11:26 INFO - PID 31527 | |8;json-parse-financial;54;52;52;52;53;53;53;53;52;53 15:11:26 INFO - PID 31527 | |9;json-stringify-tinderbox;56;55;55;56;56;57;56;56;56;55 15:11:26 INFO - PID 31527 | |10;stanford-crypto-aes;82;79;80;80;79;79;80;81;80;80 15:11:26 INFO - PID 31527 | |11;stanford-crypto-ccm;130;133;130;143;129;132;127;129;175;128 15:11:26 INFO - PID 31527 | |12;stanford-crypto-pbkdf2;193;187;185;186;186;183;187;184;185;186 15:11:26 INFO - PID 31527 | |13;stanford-crypto-sha256-iterative;71;69;68;69;68;68;68;68;71;72 15:11:26 INFO - PID 31527 | __end_tp_report 15:11:26 INFO - PID 31527 | __start_cc_report 15:11:26 INFO - PID 31527 | _x_x_mozilla_cycle_collect,2242 15:11:26 INFO - PID 31527 | __end_cc_report 15:11:26 INFO - PID 31527 | __startTimestamp1494022286232__endTimestamp 15:11:26 INFO - PID 31527 | 15:11:26 INFO - PID 31527 | ------- Summary: start ------- 15:11:26 INFO - PID 31527 | Number of tests: 14 15:11:26 INFO - PID 31527 | 15:11:26 INFO - PID 31527 | [#0] ai-astar Cycles:10 Average:111.50 Median:111.50 stddev:0.71 (0.6%) stddev-sans-first:0.73 15:11:26 INFO - PID 31527 | Values: 111.0 111.0 111.0 111.0 111.0 112.0 111.0 112.0 112.0 113.0 15:11:26 INFO - PID 31527 | 15:11:26 INFO - PID 31527 | [#1] audio-beat-detection Cycles:10 Average:134.10 Median:129.50 stddev:15.21 (11.7%) stddev-sans-first:14.51 15:11:26 INFO - PID 31527 | Values: 153.0 132.0 126.0 165.0 121.0 126.0 127.0 123.0 146.0 122.0 15:11:26 INFO - PID 31527 | 15:11:26 INFO - PID 31527 | [#2] audio-dft Cycles:10 Average:161.80 Median:162.00 stddev:1.23 (0.8%) stddev-sans-first:1.27 15:11:26 INFO - PID 31527 | Values: 161.0 164.0 161.0 162.0 163.0 162.0 160.0 161.0 161.0 163.0 15:11:26 INFO - PID 31527 | 15:11:26 INFO - PID 31527 | [#3] audio-fft Cycles:10 Average:81.00 Median:75.00 stddev:14.52 (19.4%) stddev-sans-first:15.18 15:11:26 INFO - PID 31527 | Values: 74.0 76.0 73.0 118.0 76.0 75.0 75.0 74.0 95.0 74.0 15:11:26 INFO - PID 31527 | 15:11:26 INFO - PID 31527 | [#4] audio-oscillator Cycles:10 Average:89.30 Median:83.00 stddev:10.24 (12.3%) stddev-sans-first:10.61 15:11:26 INFO - PID 31527 | Values: 83.0 84.0 104.0 83.0 83.0 83.0 96.0 83.0 84.0 110.0 15:11:26 INFO - PID 31527 | 15:11:26 INFO - PID 31527 | [#5] imaging-gaussian-blur Cycles:10 Average:113.30 Median:113.00 stddev:0.67 (0.6%) stddev-sans-first:0.71 15:11:26 INFO - PID 31527 | Values: 113.0 113.0 113.0 113.0 113.0 113.0 113.0 115.0 114.0 113.0 15:11:26 INFO - PID 31527 | 15:11:26 INFO - PID 31527 | [#6] imaging-darkroom Cycles:10 Average:149.30 Median:149.00 stddev:1.06 (0.7%) stddev-sans-first:0.93 15:11:26 INFO - PID 31527 | Values: 151.0 149.0 149.0 149.0 149.0 151.0 149.0 150.0 148.0 148.0 15:11:26 INFO - PID 31527 | 15:11:26 INFO - PID 31527 | [#7] imaging-desaturate Cycles:10 Average:93.60 Median:94.00 stddev:0.70 (0.7%) stddev-sans-first:0.73 15:11:26 INFO - PID 31527 | Values: 94.0 94.0 94.0 93.0 93.0 95.0 94.0 93.0 93.0 93.0 15:11:26 INFO - PID 31527 | 15:11:26 INFO - PID 31527 | [#8] json-parse-financial Cycles:10 Average:52.70 Median:53.00 stddev:0.67 (1.3%) stddev-sans-first:0.53 15:11:26 INFO - PID 31527 | Values: 54.0 52.0 52.0 52.0 53.0 53.0 53.0 53.0 52.0 53.0 15:11:26 INFO - PID 31527 | 15:11:26 INFO - PID 31527 | [#9] json-stringify-tinderbox Cycles:10 Average:55.80 Median:56.00 stddev:0.63 (1.1%) stddev-sans-first:0.67 15:11:26 INFO - PID 31527 | Values: 56.0 55.0 55.0 56.0 56.0 57.0 56.0 56.0 56.0 55.0 15:11:26 INFO - PID 31527 | 15:11:26 INFO - PID 31527 | [#10] stanford-crypto-aes Cycles:10 Average:80.00 Median:80.00 stddev:0.94 (1.2%) stddev-sans-first:0.67 15:11:26 INFO - PID 31527 | Values: 82.0 79.0 80.0 80.0 79.0 79.0 80.0 81.0 80.0 80.0 15:11:26 INFO - PID 31527 | 15:11:26 INFO - PID 31527 | [#11] stanford-crypto-ccm Cycles:10 Average:135.60 Median:131.00 stddev:14.56 (11.1%) stddev-sans-first:15.30 15:11:26 INFO - PID 31527 | Values: 130.0 133.0 130.0 143.0 129.0 132.0 127.0 129.0 175.0 128.0 15:11:26 INFO - PID 31527 | 15:11:26 INFO - PID 31527 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.20 Median:186.00 stddev:2.70 (1.5%) stddev-sans-first:1.33 15:11:26 INFO - PID 31527 | Values: 193.0 187.0 185.0 186.0 186.0 183.0 187.0 184.0 185.0 186.0 15:11:26 INFO - PID 31527 | 15:11:26 INFO - PID 31527 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.20 Median:69.00 stddev:1.55 (2.2%) stddev-sans-first:1.50 15:11:26 INFO - PID 31527 | Values: 71.0 69.0 68.0 69.0 68.0 68.0 68.0 68.0 71.0 72.0 15:11:26 INFO - PID 31527 | -------- Summary: end -------- 15:11:26 INFO - PID 31527 | 15:11:27 INFO - TEST-INFO | 31527: exit 0 15:11:27 INFO - TEST-OK | kraken | took 65372ms 15:11:27 INFO - SUITE-END | took 1188s 15:11:27 INFO - Completed test suite (00:19:48) 15:11:27 INFO - javascript benchmark 15:11:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6591.472291574761, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2694.3802925327172, 2726.9318267043323, 2746.8489233092173, 2749.958750618741, 2773.7226277372265, 4938.185199442097, 5015.924761128582, 5024.723640199788, 5087.338645976022, 5121.692698438096, 5021.27191557224, 5027.0699920514735, 5031.647784655075, 5035.496450354972, 5038.168702164138, 4474.306482495218, 4475.39582156408, 4479.910401791963, 4540.841070562531, 4562.0, 4543.886402839922, 4553.544645535453, 4617.2612382018715, 4621.468531118914, 4623.0115276644165, 4506.285003111388, 4556.84051058213, 4587.449506059261, 4612.630989520831, 4627.676062675614, 4046.4739583854207, 4526.637868970474, 4591.816327346902, 4594.081183763227, 4636.791344389477, 4378.211921854065, 4426.889327766799, 4428.0, 4433.9556604433865, 4489.932651010237, 4946.530079642428, 4955.801767929279, 4956.851294461172, 4995.525425084611, 5033.1695270280725, 5405.5135037846785, 5440.156775699773, 5457.126859702428, 5467.0705979983495, 5483.506484416382, 5354.999999999961, 5434.076207044812, 5442.782288708447, 5464.0, 5486.341639003305, 4952.207646776535, 5011.724355160502, 5042.697438153723, 5061.089003979319, 5065.569426598707, 5546.362168350611, 5549.861253468614, 5552.944470555323, 5558.416366281563, 5564.638298510584, 5199.2201169824075, 5271.341082364705, 5278.498542638443, 5282.128448805981, 5322.175062865262, 5346.4118946915805, 5386.91919621206, 5443.482869127427, 5444.8094316698725, 5454.181872719124, 5323.893522129536, 5358.91961620576, 5366.490183432567, 5366.6779993200535, 5372.8656783579945, 5367.543758780547, 5375.638002566953, 5375.9462405376225, 5378.3058336623835, 5388.0, 4419.204543182193, 4479.529649386767, 4488.842890498817, 4493.977530112328, 4509.255972764523, 3904.590018048121, 3918.294706952719, 3921.5882332355263, 3929.842806287774, 3942.6057394260342, 4563.977180114144, 4570.7257564546235, 4599.540045995441, 4630.21286381316, 4630.513796051366, 5384.730763461811, 5385.542228910586, 5388.91916621251, 5392.892142157213, 5394.460553944653, 4564.0, 4579.427571553556, 4580.633549316047, 4620.3993480847275, 4642.9535704643195, 4763.3807605011125, 4769.475357710649, 4821.4696383397795, 4833.202521583969, 4845.53967373099, 5086.338775959176, 5097.362829646294, 5107.157319042316, 5123.410807757155, 5156.432792392909, 5350.9732451337495, 5420.972895135499, 5439.102548079522, 5455.481729235717, 5457.863553411196, 5025.120603894377, 5062.696238225718, 5066.290719299227, 5081.8475445736685, 5085.64400491962, 5256.658317209369, 5295.894082118413, 5314.468553144655, 5362.034833729966, 5366.2487251784005, 5487.835364939058, 5498.9826882026955, 5500.669959802424, 5505.0, 5505.642133261325, 5284.656497327662, 5293.258943747801, 5323.148296272578, 5341.786328546815, 5348.358197016383, 5320.2817619620855, 5324.8136315228785, 5329.360476742816, 5335.199720042025, 5345.599080068932, 5249.895002100013, 5250.317458730341, 5255.264263003106, 5263.894722105613, 5314.441983591822, 5233.607479439133, 5243.475652434726, 5317.654352467077, 5318.202269659582, 5322.467753224647, 5163.870903227449, 5168.53483186515, 5203.401608814884, 5212.296339994053, 5213.582913366846], "value": 4926.9952007901, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [748.7379417203978, 770.5530792140557, 1052.200327750909, 1066.9519871605778, 1071.5874388360485, 25845.35386615333, 26627.0, 29936.0, 30664.0, 32444.351112977725, 33544.32911341772, 34001.48997765035, 34039.0, 34069.65930340696, 34127.14682132948, 6920.965395173017, 7041.647917604099, 7049.0, 7052.083229180225, 7069.5758254504635, 5269.235960785683, 5435.1032079707, 6878.965605171968, 7024.754133605325, 7137.036500072476, 7122.252163522833, 7155.3202445767565, 7175.856482870339, 7179.210286868441, 7185.425165986735, 7061.0, 7121.608311542837, 7126.465515086389, 7128.6079265640365, 7133.357997780173, 5229.424763276036, 5401.540869026137, 5403.918941215885, 7005.894911576331, 7072.929270707278, 23850.642240366407, 28843.56734648982, 29717.851410743024, 29797.0, 29825.105246842628, 3083.1367217179227, 3119.610048743907, 3122.4223518648896, 3127.0931429885304, 3127.7654175936896, 23922.401939951465, 23945.521089578284, 23956.041758329648, 23977.0, 24026.75973240263, 3024.6975302469796, 3031.166429231979, 3045.710657487535, 3052.4658184817567, 3065.601471808651, 138871.0, 139305.30347348299, 139394.3030284842, 139395.0, 139450.0, 41369.58630413718, 41407.37888931608, 41415.1716965659, 41440.79279603583, 41513.58486415097, 35159.4726079109, 36515.817420912725, 42141.0, 43176.78411607985, 44365.0, 11280.32318060919, 11478.081753459703, 11479.4260286987, 11513.827292590619, 11550.133739969537, 8912.598933048028, 8917.643294268157, 8949.0, 8968.641254349883, 9030.27757779376, 12700.87299126997, 12708.173968692005, 12710.110292279636, 12760.617181484571, 12860.742785144432, 5098.898022039612, 5169.327987361619, 5173.793048278102, 5208.0, 5269.156934890476, 890.9910900891037, 922.460360688991, 936.2603543200931, 938.061938061938, 959.827231098395, 20371.8981405095, 21631.242906498195, 25247.87376063108, 26242.343941401235, 26592.734072659026, 999.93000489965, 1005.3163848582888, 1008.6973907827622, 1011.4993078426226, 1016.4003238089563, 11247.381394023407, 11949.88050119505, 14086.366113524917, 14875.33061012257, 15011.0, 154157.22921385322, 154422.0, 154520.0, 154870.0, 155427.22286388496, 40805.38791918124, 40970.79514602408, 40995.79502102471, 41013.0, 41075.7946210273, 1240.9379531023412, 1276.6042526816532, 1282.064093211979, 1283.826683397748, 1297.1139215246003, 22981.0, 23298.534029319657, 24037.639435408135, 24201.152959646326, 24226.878865606264, 28956.27609309784, 29055.85472072626, 29070.854645726635, 29080.47470980507, 29088.854555727085, 134090.0, 134634.0, 134641.3267933654, 134765.0, 135219.0, 24647.383815404042, 25298.36754081163, 27065.45869082568, 27919.302017449725, 28035.29911752141, 34442.48336274908, 34483.0, 34507.4823877637, 34875.825620871736, 34886.65113348834, 24750.38124046913, 25895.352616184748, 26183.869080654476, 26439.33901652474, 26892.86553567298, 26360.968488623286, 26398.340041499116, 26544.0, 26702.0, 27075.86462067677, 28320.71679283263, 28498.857505712338, 28903.56644650374, 28929.276768080967, 28966.420671586027, 24350.0, 25168.874155629106, 25287.0, 25486.23541293764, 26259.0, 20921.790782091983, 22324.66513002274, 22994.770052299264, 23155.884220579464, 23278.185263515697], "value": 14207.511931746694, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [414.4136047492798, 423.10935480812867, 426.82713501032083, 429.1267271103304, 440.37246923134546, 16012.43956461524, 17504.73742893856, 18271.26914923405, 18296.542586435346, 18314.72527912079, 17967.281308747635, 18201.81798182018, 18478.722819157723, 19101.904490477562, 19228.903855480738, 5589.441055894398, 5596.4403559644115, 5658.236138121342, 5692.0323544997245, 5700.800471983482, 5549.472800084006, 5653.123765816305, 5653.830385088454, 5653.943460565403, 5655.123455864347, 5284.762185701653, 5507.972460137694, 5542.168674698787, 5543.805966791163, 5621.269234999465, 5499.477549632778, 5507.118860982224, 5558.416366281563, 5559.082116669742, 5561.165825126223, 4862.07620552094, 4935.555799977983, 4975.203967365204, 4978.77595508203, 4979.900401991976, 21913.890430547905, 22608.20871269514, 22633.09467621293, 22675.659865101872, 22916.77083229163, 1634.6567220883628, 1685.073209734641, 1685.6881476926808, 1685.924133413987, 1686.51934198754, 14674.55976320712, 14687.33906974178, 15195.468158614505, 15290.541283761506, 15371.308291126927, 2081.7814129516332, 2138.8609740366983, 2144.335256070623, 2153.149505945143, 2162.28644547299, 71740.64129679318, 89676.5516172415, 93196.94219875686, 93632.53183734106, 94024.0, 18321.7251741224, 18640.34758783436, 18874.339398121, 18968.0, 19517.707234391495, 35125.47311790325, 35143.648563514034, 35159.824200879346, 35372.469412958824, 35752.46371304432, 9149.405288656217, 9228.8615670765, 9411.576479058458, 9657.53732798146, 9710.465924374228, 7354.3748781353115, 7407.481476296715, 7423.554586724814, 7633.0, 7660.80847978805, 9909.157721593745, 10064.245181611408, 10078.344907580984, 10192.13366863825, 10230.23273254509, 3864.2464719380014, 3890.513685789276, 3917.8843424550423, 3933.095388060762, 4047.5142982841658, 767.8656235158936, 797.8763291689797, 809.9919000810035, 816.3102178659142, 825.2449009156554, 16447.424340148038, 16506.504804855875, 16528.09095499735, 16623.3350665972, 17074.65850683004, 799.0650938402083, 799.7643640575325, 817.9550124743078, 819.7253919936769, 824.1511243419287, 8586.914130858611, 8641.265492433087, 8744.912550874536, 9099.226565741987, 9216.354855160074, 350592.2470387632, 355928.0, 365721.0, 365974.0, 366359.0, 70302.0, 74243.25756742472, 75177.0, 75721.24278757253, 76321.0, 1028.1055481730941, 1047.4134484688598, 1080.4273734920505, 1086.9076128529005, 1088.9782204355868, 13794.172349659053, 14188.21964791905, 14190.503332383318, 14250.714985700435, 14593.124412535282, 35142.0, 35578.0, 35707.82146089253, 36152.27695446129, 36247.45628815622, 43816.0, 44378.0, 44391.77804110959, 45960.770196148806, 46352.30471542997, 8731.91268087311, 8734.868976965223, 8818.823623527622, 8926.241269492166, 9071.682491112779, 13234.602961911158, 13583.388747506584, 13775.655608609864, 13797.0, 14057.22685252321, 10113.949430252802, 10262.794744105226, 10292.588296468057, 10626.36241825493, 11293.604723834625, 10905.563777448813, 10997.835032474359, 11148.219624626197, 11160.162987775788, 11350.54597816078, 11090.0, 11139.777204456028, 11255.66233013011, 11590.0, 11649.708757280796, 8617.82764344722, 8671.956640216758, 8782.868256976279, 8838.734837954871, 9047.0, 10226.59093636276, 10400.219983501116, 10439.94780026095, 10759.085477734166, 10790.19073569501], "value": 9442.728072265552, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1222.4804458105302, 1301.772189866773, 1315.4672357695133, 1315.9276239806807, 1319.5579480873907, 9038.864417033741, 9365.688803567498, 9441.669541566047, 9443.480608566535, 9449.574769135388, 8852.645894164241, 9062.139096785795, 9079.77300567486, 9237.0, 9255.305852061088, 2028.4016215216548, 2060.7939206079423, 2071.47177469745, 2073.1603700501323, 2083.0, 1871.4011516314786, 1947.5228569000615, 2125.9468513287216, 2175.2712841198218, 2185.9234926777567, 2125.404886631738, 2203.6143674856917, 2208.2712704807454, 2210.801027907488, 2212.8119109875597, 2219.411855858199, 2237.19460994042, 2248.5053288276717, 2248.718910136233, 2252.2342403582697, 2147.5168087180414, 2149.151085321305, 2150.397888591197, 2154.827613790893, 2164.6969424280614, 9055.954720226422, 9223.0, 9233.676821311223, 9247.907520924771, 9267.814643707088, 5131.897362052738, 5285.603579731535, 5309.230161626542, 5311.840644780624, 5324.201369794523, 3000.6699263081046, 3091.938161236763, 3341.2649217172184, 3354.7316214702764, 3358.932821343584, 1057.5089124334652, 1069.946502674863, 1094.6442406217932, 1124.9943750281277, 1128.3793913347627, 13823.51617693386, 13890.097143685629, 13891.097078689852, 13904.791428128587, 13949.930250348683, 4279.679024073144, 4424.3142312941545, 4601.28680054592, 4629.537046295411, 4655.604273636779, 10182.592696292213, 10193.898061019443, 10202.030807073465, 10207.897921020694, 10213.285070045025, 5101.20931255656, 5103.413107492686, 5106.923396149061, 5114.15616423286, 5117.155669314521, 4555.088982203506, 4689.577937985529, 4802.783874725646, 4939.357883475125, 4953.405591328991, 3496.42309019009, 3584.444411116281, 3663.395539735967, 3669.4495825625836, 3727.7763334200035, 987.9110880020833, 1064.6593090211059, 1075.198976762317, 1085.7122862441458, 1099.2250463423268, 597.489146779506, 650.6909218121355, 691.512483698991, 730.18219594055, 740.0638192686256, 5143.099957507421, 5231.529162375405, 5253.500917412916, 5325.600579956519, 5341.893162136736, 814.3607268294361, 836.7071524966213, 838.3460900497622, 848.7962888906618, 862.66787286895, 3033.135556366425, 3060.1125673554866, 3064.800787948821, 3087.6140482439696, 3176.587043684306, 11561.479733412016, 11961.521539138337, 11968.581099661471, 11981.700457488632, 12032.217905836093, 11817.468213930393, 11836.408179590986, 11846.111541634413, 11857.762844743056, 11864.762704745857, 7063.187733410724, 7172.713091476387, 7228.1326240850385, 7307.3058059484265, 7333.963330183422, 4999.625028122832, 4999.625028122832, 5083.440821509705, 5205.427402985744, 5884.6469211847425, 10469.476526173508, 10741.409222492526, 10938.015578597646, 10987.175961802737, 11227.38249396291, 10952.0, 11061.723456913642, 11203.21577489568, 11259.437028148724, 11263.211575189658, 7544.4341674373545, 7781.105172905189, 7856.09654889695, 7859.724909628136, 7870.331021862977, 8773.122687731176, 8823.69117080899, 8830.955845220733, 8833.823323533621, 8835.779105522413, 7263.891041634274, 7413.147488038944, 7419.332260096618, 7577.848443031219, 7695.384369250559, 7316.8902466464115, 7318.524295920748, 7414.777556673308, 7465.0, 7778.611069446391, 6714.59712417237, 6827.21487028998, 6857.5199736018, 6876.243613202644, 6903.378695917391, 4908.312836203006, 5015.974920125376, 5024.321716568363, 5062.594992400525, 5127.948720512747, 4873.4639189689815, 4965.4786247444945, 4980.302757613865, 4995.0, 5007.098722230034], "value": 4439.860432793312, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [366.70846676891887, 379.6981399787169, 380.61557826595134, 383.28708602000256, 383.84837988994366, 5285.920711189336, 5297.549708274792, 5346.572274218063, 5356.643077046102, 5361.705106219157, 4991.825286114987, 5194.039102765991, 5199.194124910636, 5220.608454365928, 5243.161094224924, 3973.682105431573, 3984.063745019918, 3985.3224951758125, 3988.521377434712, 3993.5607083220966, 3971.60283971602, 3981.3231750602467, 3988.8205030773684, 3990.1620659661507, 4000.3598524604918, 3799.25914446683, 3937.1338305572726, 3954.070793363557, 3975.8012099394914, 3982.701297402692, 3923.2149372035797, 3936.940945885814, 3940.034691500586, 3951.0, 3953.090789118519, 3471.8090505022215, 3474.339875423687, 3477.23500829817, 3487.808170550619, 3488.982555087234, 4876.195427754416, 4982.850514484571, 5021.573166280834, 5024.974875125601, 5025.14572522672, 4608.248865431115, 4643.3963584733765, 4645.698029628097, 4669.556392142741, 4680.157571637104, 5464.754086066137, 5508.669479831183, 5513.228148059235, 5515.696636684981, 5546.0, 3826.559945606263, 3882.611738826123, 3886.4753258310047, 3887.455756194135, 3888.2223555289056, 5547.0, 5626.971865140648, 5744.82765517031, 5765.4522820332, 5873.588848780587, 3976.661983731415, 3983.8605648802154, 3988.481497405319, 3990.3016972029777, 3999.24014437256, 3980.8208630611693, 4036.59634036594, 4045.0089728016824, 4046.0896298333228, 4061.6750659947133, 4405.823767049346, 4518.887027824265, 4526.099038403879, 4591.219492686251, 4611.9077618447445, 5492.972535137299, 5502.257195278667, 5508.476694713996, 5518.255035570147, 5524.640898341595, 1366.5695305978586, 1378.6484446466086, 1388.0977364713017, 1389.1317926296065, 1390.1033833177544, 923.5982347678647, 936.0826390137568, 938.3853575907791, 942.7878727286305, 950.7052813627797, 4862.343583616166, 4867.58625516835, 4870.878228044327, 4878.585320247747, 4889.119958407521, 5756.222909907193, 5761.164631128463, 5769.59612827098, 5774.220480235199, 5778.971105144447, 4701.78841952113, 4810.855674329776, 4858.5384388483035, 4867.537583929521, 4868.610511159099, 3012.5631783391286, 3043.9847800760854, 3048.679888611708, 3053.1451193665807, 3053.6640969493337, 5176.430592634807, 5369.7310638042845, 5380.78476860929, 5384.623076384693, 5386.892262154735, 5836.153757705109, 5847.222569279643, 5866.941330586639, 5874.148248504029, 5903.0, 5320.707361095099, 5321.441248668912, 5345.358556973188, 5360.946390536123, 5367.812126575629, 5520.447955204448, 5706.543476521785, 5709.286339207599, 5712.314522257356, 5718.313802343745, 5364.195370694427, 5553.500184983371, 5620.185073164286, 5631.352394474688, 5645.68948707825, 5203.531682148625, 5265.157574788169, 5350.384705758732, 5358.383785864676, 5384.730763461889, 4352.978235108804, 4381.145676593033, 4393.0774537347515, 4396.670249731219, 4404.537523560109, 5663.8867222656145, 5666.801661941812, 5688.971555142198, 5745.770169193185, 5763.884722305446, 5525.0, 5530.419305972815, 5533.723313834212, 5545.556355491471, 5547.805826796042, 5510.504054635102, 5533.695646739468, 5542.140968149944, 5556.527695145958, 5576.10782274834, 5366.490183432567, 5454.809081682122, 5462.126059830408, 5496.509716330239, 5506.311711036121, 5498.120300751861, 5526.171074338881, 5557.110862262019, 5559.444055594408, 5568.777248909998, 5266.368035835724, 5314.441983591667, 5416.7562459688625, 5426.5387442066285, 5430.864228394322], "value": 4157.983161021415, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [736.8305289783353, 738.8448425830576, 757.3486801350839, 760.9771706848793, 764.7896828372201, 12280.447379867905, 13392.129511581741, 13399.799003014938, 13467.79798303025, 13522.864771352284, 13312.534061307857, 15483.612909677264, 16034.198290085478, 16175.43385981491, 16178.514644560653, 903.4127816919006, 923.4782347973407, 924.1728652855691, 925.7685578605349, 927.5872236854602, 908.7182973278304, 910.167197014734, 910.6175406329324, 911.7173676160412, 912.4388501071826, 947.3747326764337, 955.3980991975046, 955.5461155950937, 956.7560272130597, 956.9377990430635, 937.2408349237106, 937.723371605378, 943.7829299261174, 945.2815859946421, 945.4280160502866, 685.25649670108, 692.62944324786, 693.5665209244222, 695.2838576266454, 696.0672698583871, 14058.92970535151, 14059.437622495088, 14200.076994995292, 14261.215633140173, 14453.349599268058, 14363.353649085817, 14551.563453096423, 14971.475998340005, 15008.699826003418, 15009.099454032794, 11623.593174238946, 11820.23168494045, 11828.585999490062, 11859.229150105215, 11867.58463453775, 2887.5379939209834, 2893.2477555835426, 2904.7385735283924, 2916.5625156226524, 2918.1391489510647, 70035.0, 71886.64056679736, 72328.27671723321, 72680.6365968172, 74325.25674743237, 12202.572909948109, 12220.694482637828, 12293.323867187211, 12343.382830858422, 12358.50565977351, 20750.06624701892, 20878.37364879056, 21293.0, 21299.78700212978, 21336.359909202747, 5845.4446827552165, 5891.49922256613, 5894.8821023579285, 5906.586538942232, 5913.733881975321, 2848.3875966667265, 2877.3813630069635, 2899.9565006524917, 2900.9709902901122, 2902.7097290271226, 3337.332533493311, 3412.709919656857, 3417.9145521362166, 3420.1278673937986, 3424.33225521026, 628.3967391304399, 632.5666918160982, 633.4457349818908, 634.1597383466925, 634.4385219081054, 578.8668606220508, 593.2850914647839, 593.8960681880653, 600.4775845014865, 602.2231321595181, 9116.179543841086, 9415.482148481762, 9839.950800245953, 10131.949340253399, 10220.693379198636, 560.6103757888228, 562.8733534954603, 566.0575142387878, 570.067938919869, 584.1821449970076, 4052.3516237401873, 4060.573639767841, 4064.2684316822665, 4081.6122468365456, 4093.529244136903, 61181.38818611757, 61589.69205153945, 62360.68819655963, 63684.36315636784, 65815.34184658188, 54454.45545544573, 57071.71464142653, 57370.0, 57434.42565574291, 58056.4194358051, 49594.25608615873, 49746.502534974185, 50490.0, 51206.23190652143, 51605.741971290656, 8187.385946053951, 10589.523471443958, 11433.0, 11464.656060318204, 11639.5344186235, 24521.632175517738, 25226.621600675637, 25228.621570676823, 25840.741592584585, 26655.067072652364, 42415.0, 43281.0, 44558.57056209083, 47308.763456182496, 47870.521294788, 13134.60596182116, 13458.125221860548, 13676.31618419095, 13715.657108571966, 13946.79079813824, 15134.470293539673, 15176.620584485476, 15303.46437874669, 15313.234338282818, 15481.0, 13660.248686321951, 13885.583432497042, 14055.297235137998, 14212.857871421567, 14402.20787856678, 13047.412866420844, 13135.277559734306, 13159.736805264032, 13264.336783160996, 13474.528391506643, 13976.301184940508, 14037.929810350883, 14085.577432677037, 14244.145351278956, 14361.138331700131, 9473.621055157717, 9750.463724495221, 9788.559514821707, 9794.853077203705, 10001.34991225568, 9538.475383853955, 9639.469829159463, 9643.807123857623, 9706.417614943126, 10061.094501494901], "value": 6720.6090120679555, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1513.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 111.0, 111.0, 111.0, 111.0, 112.0, 111.0, 112.0, 112.0, 113.0], "value": 111.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 132.0, 126.0, 165.0, 121.0, 126.0, 127.0, 123.0, 146.0, 122.0], "value": 134.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 164.0, 161.0, 162.0, 163.0, 162.0, 160.0, 161.0, 161.0, 163.0], "value": 161.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 73.0, 118.0, 76.0, 75.0, 75.0, 74.0, 95.0, 74.0], "value": 81.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 104.0, 83.0, 83.0, 83.0, 96.0, 83.0, 84.0, 110.0], "value": 89.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 115.0, 114.0, 113.0], "value": 113.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 149.0, 149.0, 149.0, 149.0, 151.0, 149.0, 150.0, 148.0, 148.0], "value": 149.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 93.0, 93.0, 95.0, 94.0, 93.0, 93.0, 93.0], "value": 93.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 52.0, 52.0, 52.0, 53.0, 53.0, 53.0, 53.0, 52.0, 53.0], "value": 52.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 55.0, 56.0, 56.0, 57.0, 56.0, 56.0, 56.0, 55.0], "value": 55.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 79.0, 80.0, 80.0, 79.0, 79.0, 80.0, 81.0, 80.0, 80.0], "value": 80.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 133.0, 130.0, 143.0, 129.0, 132.0, 127.0, 129.0, 175.0, 128.0], "value": 135.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [193.0, 187.0, 185.0, 186.0, 186.0, 183.0, 187.0, 184.0, 185.0, 186.0], "value": 186.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 69.0, 68.0, 69.0, 68.0, 68.0, 68.0, 68.0, 71.0, 72.0], "value": 69.2, "unit": "score"}]}]} 15:11:27 INFO - Return code: 0 15:11:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:11:28 INFO - # TBPL SUCCESS # 15:11:28 INFO - Running post-action listener: _resource_record_post_action 15:11:28 INFO - [mozharness: 2017-05-05 22:11:28.029931Z] Finished run-tests step (success) 15:11:28 INFO - Running post-run listener: _resource_record_post_run 15:11:28 INFO - Total resource usage - Wall time: 1205s; CPU: 13.0%; Read bytes: 4096; Write bytes: 357203968; Read time: 12; Write time: 179772 15:11:28 INFO - TinderboxPrint: CPU usage
12.9% 15:11:28 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 15:11:28 INFO - TinderboxPrint: I/O write bytes / time
357,203,968 / 179,772 15:11:28 INFO - TinderboxPrint: CPU idle
8,372.3 (87.0%) 15:11:28 INFO - TinderboxPrint: CPU user
1,201.8 (12.5%) 15:11:28 INFO - TinderboxPrint: Swap in / out
0 / 0 15:11:28 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 90640384; Read time: 0; Write time: 51408 15:11:28 INFO - run-tests - Wall time: 1189s; CPU: 13.0%; Read bytes: 4096; Write bytes: 266563584; Read time: 12; Write time: 128364 15:11:28 INFO - Running post-run listener: _upload_blobber_files 15:11:28 INFO - Blob upload gear active. 15:11:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:11:28 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 15:11:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:11:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:11:29 INFO - (blobuploader) - INFO - Open directory for files ... 15:11:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:11:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:11:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:11:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:11:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:11:29 INFO - (blobuploader) - INFO - Done attempting. 15:11:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:11:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:11:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:11:30 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:11:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:11:30 INFO - (blobuploader) - INFO - Done attempting. 15:11:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:11:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:11:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:11:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:11:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:11:34 INFO - (blobuploader) - INFO - Done attempting. 15:11:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:11:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:11:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:11:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:11:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:11:35 INFO - (blobuploader) - INFO - Done attempting. 15:11:35 INFO - (blobuploader) - INFO - Iteration through files over. 15:11:35 INFO - Return code: 0 15:11:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:11:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:11:35 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce885a30fe6b89ab47f4023bd0d411c3e20c961fec691cdd54f0c03fb13be23db244c59e7b2b6a7500b519e2e6ed53ed578c8d50651399cd85c2eaf462a7d5ce", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5d192fae865bac06929ebcfb6a376fc39f0108c22e6672b0cba06a31e96785bfd06b4464466683aefc199f088c12cf33c5f7512cd17661d76ea92a35e4d39c9c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/039eab9e8e4db732ac373ab57c12d72097a142bf091bf8c24dd53f7f481ef43b1dc8757ba5c4ae6715835f387b66dafb7d8a6c7b8aac4c32dfbea573d46e4b4c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0e9c779652db7e2c4ca75984f2befa15a928b22b660e8fc7ef7db4a6763c8aeced990d3a37f983df0dfa62791343262d7b1456b11d625dc8f6fcc34d6616fa66"} 15:11:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:11:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:11:35 INFO - Contents: 15:11:35 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce885a30fe6b89ab47f4023bd0d411c3e20c961fec691cdd54f0c03fb13be23db244c59e7b2b6a7500b519e2e6ed53ed578c8d50651399cd85c2eaf462a7d5ce", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5d192fae865bac06929ebcfb6a376fc39f0108c22e6672b0cba06a31e96785bfd06b4464466683aefc199f088c12cf33c5f7512cd17661d76ea92a35e4d39c9c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/039eab9e8e4db732ac373ab57c12d72097a142bf091bf8c24dd53f7f481ef43b1dc8757ba5c4ae6715835f387b66dafb7d8a6c7b8aac4c32dfbea573d46e4b4c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0e9c779652db7e2c4ca75984f2befa15a928b22b660e8fc7ef7db4a6763c8aeced990d3a37f983df0dfa62791343262d7b1456b11d625dc8f6fcc34d6616fa66"} 15:11:35 INFO - Running post-run listener: copy_logs_to_upload_dir 15:11:35 INFO - Copying logs to upload dir... 15:11:35 INFO - mkdir: /builds/slave/test/build/upload/logs 15:11:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1259.076889 ========= master_lag: 0.17 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2017-05-05 15:11:34.806068) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-05 15:11:34.811773) ========= 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=483e410453545d07cca6935c000001ab-1494021034.206561-1957242300 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce885a30fe6b89ab47f4023bd0d411c3e20c961fec691cdd54f0c03fb13be23db244c59e7b2b6a7500b519e2e6ed53ed578c8d50651399cd85c2eaf462a7d5ce", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5d192fae865bac06929ebcfb6a376fc39f0108c22e6672b0cba06a31e96785bfd06b4464466683aefc199f088c12cf33c5f7512cd17661d76ea92a35e4d39c9c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/039eab9e8e4db732ac373ab57c12d72097a142bf091bf8c24dd53f7f481ef43b1dc8757ba5c4ae6715835f387b66dafb7d8a6c7b8aac4c32dfbea573d46e4b4c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0e9c779652db7e2c4ca75984f2befa15a928b22b660e8fc7ef7db4a6763c8aeced990d3a37f983df0dfa62791343262d7b1456b11d625dc8f6fcc34d6616fa66"} build_url:https://queue.taskcluster.net/v1/task/U-qO4zg9Tp-Ne7DNaSe-AA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011123 build_url: 'https://queue.taskcluster.net/v1/task/U-qO4zg9Tp-Ne7DNaSe-AA/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce885a30fe6b89ab47f4023bd0d411c3e20c961fec691cdd54f0c03fb13be23db244c59e7b2b6a7500b519e2e6ed53ed578c8d50651399cd85c2eaf462a7d5ce", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5d192fae865bac06929ebcfb6a376fc39f0108c22e6672b0cba06a31e96785bfd06b4464466683aefc199f088c12cf33c5f7512cd17661d76ea92a35e4d39c9c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/039eab9e8e4db732ac373ab57c12d72097a142bf091bf8c24dd53f7f481ef43b1dc8757ba5c4ae6715835f387b66dafb7d8a6c7b8aac4c32dfbea573d46e4b4c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0e9c779652db7e2c4ca75984f2befa15a928b22b660e8fc7ef7db4a6763c8aeced990d3a37f983df0dfa62791343262d7b1456b11d625dc8f6fcc34d6616fa66"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-05 15:11:34.839901) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 15:11:34.840233) ========= 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=483e410453545d07cca6935c000001ab-1494021034.206561-1957242300 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004473 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 15:11:34.920948) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-05 15:11:34.921270) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-05 15:11:34.924590) ========= ========= Total master_lag: 0.33 =========