builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-010 starttime: 1490284304.68 results: success (0) revision: b0494b853b51c13deef16cb463cb30f3e4c0e1d0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-23 08:51:44.676766) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-23 08:51:44.677240) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-23 08:51:44.677538) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-23 08:51:44.693114) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-23 08:51:44.693451) ========= 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=eca2ab57546de7b41f256b19000001ef-1490284302.918161-268569705 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-03-23 08:51:44-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 114M=0s 2017-03-23 08:51:44 (114 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.195406 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-23 08:51:44.928241) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-23 08:51:44.928586) ========= 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=eca2ab57546de7b41f256b19000001ef-1490284302.918161-268569705 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.056258 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-23 08:51:44.994985) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-23 08:51:44.995335) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b0494b853b51c13deef16cb463cb30f3e4c0e1d0 --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 b0494b853b51c13deef16cb463cb30f3e4c0e1d0 --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=eca2ab57546de7b41f256b19000001ef-1490284302.918161-268569705 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-03-23 08:51:45,050 truncating revision to first 12 chars 2017-03-23 08:51:45,050 Setting DEBUG logging. 2017-03-23 08:51:45,050 attempt 1/10 2017-03-23 08:51:45,051 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b0494b853b51?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-23 08:51:45,364 unpacking tar archive at: mozilla-central-b0494b853b51/testing/mozharness/ program finished with exit code 0 elapsedTime=0.656424 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-23 08:51:45.665467) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-23 08:51:45.665774) ========= script_repo_revision: b0494b853b51c13deef16cb463cb30f3e4c0e1d0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-23 08:51:45.666142) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-23 08:51:45.666437) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-23 08:51:45.676965) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2017-03-23 08:51:45.677271) ========= /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=eca2ab57546de7b41f256b19000001ef-1490284302.918161-268569705 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:51:46 INFO - MultiFileLogger online at 20170323 08:51:46 in /builds/slave/test 08:51:46 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 08:51:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:51:46 INFO - {'append_to_log': False, 08:51:46 INFO - 'base_work_dir': '/builds/slave/test', 08:51:46 INFO - 'blob_upload_branch': 'Firefox', 08:51:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:51:46 INFO - 'branch': 'Firefox', 08:51:46 INFO - 'buildbot_json_path': 'buildprops.json', 08:51:46 INFO - 'config_files': ('talos/linux_config.py',), 08:51:46 INFO - 'default_actions': ('clobber', 08:51:46 INFO - 'read-buildbot-config', 08:51:46 INFO - 'download-and-extract', 08:51:46 INFO - 'populate-webroot', 08:51:46 INFO - 'create-virtualenv', 08:51:46 INFO - 'install', 08:51:46 INFO - 'run-tests'), 08:51:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:51:46 INFO - 'download_minidump_stackwalk': True, 08:51:46 INFO - 'download_symbols': 'ondemand', 08:51:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:51:46 INFO - 'tooltool.py': '/tools/tooltool.py', 08:51:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:51:46 INFO - '/tools/misc-python/virtualenv.py')}, 08:51:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:51:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:51:46 INFO - 'gecko_profile': False, 08:51:46 INFO - 'gecko_profile_interval': 0, 08:51:46 INFO - 'installer_path': 'installer.exe', 08:51:46 INFO - 'log_level': 'info', 08:51:46 INFO - 'log_name': 'talos', 08:51:46 INFO - 'log_to_console': True, 08:51:46 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:51:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:51:46 INFO - 'opt_config_files': (), 08:51:46 INFO - 'pip_index': False, 08:51:46 INFO - 'suite': 'dromaeojs', 08:51:46 INFO - 'system_bits': '32', 08:51:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:51:46 INFO - 'title': 'talos-linux64-ix-010', 08:51:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:51:46 INFO - 'use_talos_json': True, 08:51:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:51:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:51:46 INFO - 'work_dir': 'build'} 08:51:46 INFO - [mozharness: 2017-03-23 15:51:46.025855Z] Running clobber step. 08:51:46 INFO - Running pre-action listener: _resource_record_pre_action 08:51:46 INFO - Running main action method: clobber 08:51:46 INFO - rmtree: /builds/slave/test/build 08:51:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:51:52 INFO - Running post-action listener: _resource_record_post_action 08:51:52 INFO - [mozharness: 2017-03-23 15:51:52.521034Z] Finished clobber step (success) 08:51:52 INFO - [mozharness: 2017-03-23 15:51:52.521199Z] Running read-buildbot-config step. 08:51:52 INFO - Running pre-action listener: _resource_record_pre_action 08:51:52 INFO - Running main action method: read_buildbot_config 08:51:52 INFO - Using buildbot properties: 08:51:52 INFO - { 08:51:52 INFO - "project": "", 08:51:52 INFO - "product": "firefox", 08:51:52 INFO - "who": "ffxbld@noreply.mozilla.org", 08:51:52 INFO - "installer_path": "public/build/target.tar.bz2", 08:51:52 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 08:51:52 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 08:51:52 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 08:51:52 INFO - "stage_platform": "linux64-pgo", 08:51:52 INFO - "basedir": "/builds/slave/test", 08:51:52 INFO - "buildnumber": 92, 08:51:52 INFO - "platform": "ubuntu64_hw", 08:51:52 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 08:51:52 INFO - "slavebuilddir": "test", 08:51:52 INFO - "taskId": "VQ67TAXKTPO_yW80805CXA", 08:51:52 INFO - "branch": "mozilla-central", 08:51:52 INFO - "script_repo_revision": "production", 08:51:52 INFO - "revision": "b0494b853b51c13deef16cb463cb30f3e4c0e1d0", 08:51:52 INFO - "slavename": "talos-linux64-ix-010", 08:51:52 INFO - "repo_path": "mozilla-central" 08:51:52 INFO - } 08:51:52 INFO - Finding installer, test and symbols from parent task. 08:51:52 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VQ67TAXKTPO_yW80805CXA'}, attempt #1 08:51:53 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VQ67TAXKTPO_yW80805CXA'}, attempt #1 08:51:53 INFO - Task dependencies: LyO05s08SuOSAGNFWIebQw 08:51:53 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LyO05s08SuOSAGNFWIebQw'}, attempt #1 08:51:53 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/LyO05s08SuOSAGNFWIebQw/artifacts/public/build/target.tar.bz2 08:51:53 INFO - Set test_url: https://queue.taskcluster.net/v1/task/LyO05s08SuOSAGNFWIebQw/artifacts/public/build/test_packages.json 08:51:53 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/LyO05s08SuOSAGNFWIebQw/artifacts/public/build/target.crashreporter-symbols.zip 08:51:53 INFO - Running post-action listener: _resource_record_post_action 08:51:53 INFO - [mozharness: 2017-03-23 15:51:53.947702Z] Finished read-buildbot-config step (success) 08:51:53 INFO - [mozharness: 2017-03-23 15:51:53.947924Z] Running download-and-extract step. 08:51:53 INFO - Running pre-action listener: _resource_record_pre_action 08:51:53 INFO - Running main action method: download_and_extract 08:51:53 INFO - mkdir: /builds/slave/test/build/tests 08:51:53 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')]} 08:51:53 INFO - trying https://queue.taskcluster.net/v1/task/LyO05s08SuOSAGNFWIebQw/artifacts/public/build/target.test_packages.json 08:51:53 INFO - Downloading https://queue.taskcluster.net/v1/task/LyO05s08SuOSAGNFWIebQw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 08:51:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LyO05s08SuOSAGNFWIebQw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 08:51:54 INFO - Downloaded 986 bytes. 08:51:54 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 08:51:54 INFO - Using the following test package requirements: 08:51:54 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 08:51:54 INFO - u'common': [u'target.common.tests.zip'], 08:51:54 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 08:51:54 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 08:51:54 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 08:51:54 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 08:51:54 INFO - u'mozbase': [u'target.common.tests.zip'], 08:51:54 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 08:51:54 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 08:51:54 INFO - u'web-platform': [u'target.common.tests.zip', 08:51:54 INFO - u'target.web-platform.tests.zip'], 08:51:54 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 08:51:54 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 08:51:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LyO05s08SuOSAGNFWIebQw/artifacts/public/build/target.common.tests.zip 08:51:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LyO05s08SuOSAGNFWIebQw/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:51:54 INFO - Fetch https://queue.taskcluster.net/v1/task/LyO05s08SuOSAGNFWIebQw/artifacts/public/build/target.common.tests.zip into memory 08:51:55 INFO - Expected file size: 60249174 08:51:55 INFO - Obtained file size: 60249174 08:52:05 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 08:52:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LyO05s08SuOSAGNFWIebQw/artifacts/public/build/target.common.tests.zip 08:52:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LyO05s08SuOSAGNFWIebQw/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:52:05 INFO - Fetch https://queue.taskcluster.net/v1/task/LyO05s08SuOSAGNFWIebQw/artifacts/public/build/target.common.tests.zip into memory 08:52:06 INFO - Expected file size: 60249174 08:52:06 INFO - Obtained file size: 60249174 08:52:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LyO05s08SuOSAGNFWIebQw/artifacts/public/build/target.talos.tests.zip 08:52:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LyO05s08SuOSAGNFWIebQw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 08:52:19 INFO - Fetch https://queue.taskcluster.net/v1/task/LyO05s08SuOSAGNFWIebQw/artifacts/public/build/target.talos.tests.zip into memory 08:52:19 INFO - Expected file size: 14404171 08:52:19 INFO - Obtained file size: 14404171 08:52:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:52:20 INFO - trying https://queue.taskcluster.net/v1/task/LyO05s08SuOSAGNFWIebQw/artifacts/public/build/target.tar.bz2 08:52:20 INFO - Downloading https://queue.taskcluster.net/v1/task/LyO05s08SuOSAGNFWIebQw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 08:52:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LyO05s08SuOSAGNFWIebQw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 08:52:21 INFO - Downloaded 67898884 bytes. 08:52:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LyO05s08SuOSAGNFWIebQw/artifacts/public/build/target.tar.bz2 08:52:21 INFO - mkdir: /builds/slave/test/properties 08:52:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:52:21 INFO - Writing to file /builds/slave/test/properties/build_url 08:52:21 INFO - Contents: 08:52:21 INFO - build_url:https://queue.taskcluster.net/v1/task/LyO05s08SuOSAGNFWIebQw/artifacts/public/build/target.tar.bz2 08:52:21 INFO - Running post-action listener: _resource_record_post_action 08:52:21 INFO - Running post-action listener: set_extra_try_arguments 08:52:21 INFO - [mozharness: 2017-03-23 15:52:21.407454Z] Finished download-and-extract step (success) 08:52:21 INFO - [mozharness: 2017-03-23 15:52:21.407551Z] Running populate-webroot step. 08:52:21 INFO - Running pre-action listener: _resource_record_pre_action 08:52:21 INFO - Running main action method: populate_webroot 08:52:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:52:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:52:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:52:21 INFO - '--tppagecycles', 08:52:21 INFO - '7'], 08:52:21 INFO - 'tests': ['tsvgm']}}, 08:52:21 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:52:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:52:21 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:52:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:52:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:52:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:52:21 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:52:21 INFO - 'talos_options': ['--disable-e10s'], 08:52:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:52:21 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:52:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:52:21 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:52:21 INFO - 'talos_options': ['--disable-e10s'], 08:52:21 INFO - 'tests': ['damp', 'tps']}, 08:52:21 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:52:21 INFO - 'tests': ['damp', 'tps']}, 08:52:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:52:21 INFO - 'tests': ['dromaeo_dom']}, 08:52:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:52:21 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:52:21 INFO - 'tests': ['basic_compositor_video']}, 08:52:21 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:52:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:52:21 INFO - 'tests': ['a11yr', 08:52:21 INFO - 'ts_paint', 08:52:21 INFO - 'tpaint', 08:52:21 INFO - 'sessionrestore', 08:52:21 INFO - 'sessionrestore_no_auto_restore', 08:52:21 INFO - 'tabpaint']}, 08:52:21 INFO - 'other-e10s': {'tests': ['a11yr', 08:52:21 INFO - 'ts_paint', 08:52:21 INFO - 'tpaint', 08:52:21 INFO - 'sessionrestore', 08:52:21 INFO - 'sessionrestore_no_auto_restore', 08:52:21 INFO - 'tabpaint']}, 08:52:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:52:21 INFO - 'ts_paint', 08:52:21 INFO - 'tpaint', 08:52:21 INFO - 'sessionrestore', 08:52:21 INFO - 'sessionrestore_no_auto_restore', 08:52:21 INFO - 'tabpaint']}, 08:52:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:52:21 INFO - 'ts_paint', 08:52:21 INFO - 'tpaint', 08:52:21 INFO - 'sessionrestore', 08:52:21 INFO - 'sessionrestore_no_auto_restore', 08:52:21 INFO - 'tabpaint']}, 08:52:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:52:21 INFO - 'tests': ['a11yr', 08:52:21 INFO - 'ts_paint', 08:52:21 INFO - 'tpaint', 08:52:21 INFO - 'sessionrestore', 08:52:21 INFO - 'sessionrestore_no_auto_restore', 08:52:21 INFO - 'tabpaint']}, 08:52:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:52:21 INFO - 'tests': ['a11yr', 08:52:21 INFO - 'ts_paint', 08:52:21 INFO - 'tpaint', 08:52:21 INFO - 'sessionrestore', 08:52:21 INFO - 'sessionrestore_no_auto_restore', 08:52:21 INFO - 'tabpaint']}, 08:52:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:52:21 INFO - 'tests': ['tsvgx', 08:52:21 INFO - 'tsvgr_opacity', 08:52:21 INFO - 'tart', 08:52:21 INFO - 'tscrollx', 08:52:21 INFO - 'cart', 08:52:21 INFO - 'tsvg_static']}, 08:52:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:52:21 INFO - 'tsvgr_opacity', 08:52:21 INFO - 'tart', 08:52:21 INFO - 'tscrollx', 08:52:21 INFO - 'cart', 08:52:21 INFO - 'tsvg_static']}, 08:52:21 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:52:21 INFO - 'talos_options': ['--disable-e10s'], 08:52:21 INFO - 'tests': ['tp5o']}, 08:52:21 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:52:21 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:52:21 INFO - 'talos_options': ['--disable-e10s', 08:52:21 INFO - '--xperf_path', 08:52:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:52:21 INFO - 'tests': ['tp5n']}, 08:52:21 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:52:21 INFO - 'talos_options': ['--xperf_path', 08:52:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:52:21 INFO - 'tests': ['tp5n']}}, 08:52:21 INFO - 'talos.zip': {'path': '', 08:52:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:52:21 INFO - Running post-action listener: _resource_record_post_action 08:52:21 INFO - [mozharness: 2017-03-23 15:52:21.420721Z] Finished populate-webroot step (success) 08:52:21 INFO - [mozharness: 2017-03-23 15:52:21.420812Z] Running create-virtualenv step. 08:52:21 INFO - Running pre-action listener: _resource_record_pre_action 08:52:21 INFO - Running main action method: create_virtualenv 08:52:21 INFO - Creating virtualenv /builds/slave/test/build/venv 08:52:21 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 08:52:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:52:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:52:21 INFO - Using real prefix '/usr' 08:52:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:52:22 INFO - Installing distribute.............................................................................................................................................................................................done. 08:52:24 INFO - Installing pip.................done. 08:52:24 INFO - Return code: 0 08:52:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:52:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:52:24 INFO - Reading from file tmpfile_stdout 08:52:24 INFO - Output received: 08:52:24 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) 08:52:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:52:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:52:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:52:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:52:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:52:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17c91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1806a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a67310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a64400>, '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 0x1a653b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a65840>, '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': 'eca2ab57546de7b41f256b19000001ef-1490284302.918161-268569705', '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 08:52:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:52:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:52:24 INFO - Using env: {'DISPLAY': ':0', 08:52:24 INFO - 'HOME': '/home/cltbld', 08:52:24 INFO - 'LANG': 'en_US.UTF-8', 08:52:24 INFO - 'LANGUAGE': 'en_US:en', 08:52:24 INFO - 'LOGNAME': 'cltbld', 08:52:24 INFO - 'MAIL': '/var/mail/cltbld', 08:52:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:52:24 INFO - 'MOZ_NO_REMOTE': '1', 08:52:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:52:24 INFO - 'NO_EM_RESTART': '1', 08:52:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:52:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:52:24 INFO - 'PWD': '/builds/slave/test', 08:52:24 INFO - 'SHELL': '/bin/bash', 08:52:24 INFO - 'SHLVL': '1', 08:52:24 INFO - 'TERM': 'linux', 08:52:24 INFO - 'TMOUT': '86400', 08:52:24 INFO - 'USER': 'cltbld', 08:52:24 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1490284302.918161-268569705', 08:52:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:52:24 INFO - '_': '/tools/buildbot/bin/python'} 08:52:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:52:24 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 08:52:24 INFO - Cleaning up... 08:52:24 INFO - Return code: 0 08:52:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:52:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:52:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:52:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:52:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:52:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x17c91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1806a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a67310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a64400>, '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 0x1a653b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a65840>, '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': 'eca2ab57546de7b41f256b19000001ef-1490284302.918161-268569705', '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 08:52:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:52:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:52:24 INFO - Using env: {'DISPLAY': ':0', 08:52:24 INFO - 'HOME': '/home/cltbld', 08:52:24 INFO - 'LANG': 'en_US.UTF-8', 08:52:24 INFO - 'LANGUAGE': 'en_US:en', 08:52:24 INFO - 'LOGNAME': 'cltbld', 08:52:24 INFO - 'MAIL': '/var/mail/cltbld', 08:52:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:52:24 INFO - 'MOZ_NO_REMOTE': '1', 08:52:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:52:24 INFO - 'NO_EM_RESTART': '1', 08:52:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:52:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:52:24 INFO - 'PWD': '/builds/slave/test', 08:52:24 INFO - 'SHELL': '/bin/bash', 08:52:24 INFO - 'SHLVL': '1', 08:52:24 INFO - 'TERM': 'linux', 08:52:24 INFO - 'TMOUT': '86400', 08:52:24 INFO - 'USER': 'cltbld', 08:52:24 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1490284302.918161-268569705', 08:52:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:52:24 INFO - '_': '/tools/buildbot/bin/python'} 08:52:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:52:25 INFO - Downloading/unpacking psutil>=3.1.1 08:52:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:52:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:52:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:52:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:52:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:52:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:52:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:52:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:52:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:52:27 INFO - Installing collected packages: psutil 08:52:27 INFO - Running setup.py install for psutil 08:52:27 INFO - building 'psutil._psutil_linux' extension 08:52:27 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 08:52:28 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 08:52:28 INFO - building 'psutil._psutil_posix' extension 08:52:28 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 08:52:28 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 08:52:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:52:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:52:28 INFO - Successfully installed psutil 08:52:28 INFO - Cleaning up... 08:52:28 INFO - Return code: 0 08:52:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:52:28 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')]} 08:52:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:52:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:28 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')]} 08:52:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:52:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:28 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 0x17c91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1806a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a67310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a64400>, '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 0x1a653b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a65840>, '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': 'eca2ab57546de7b41f256b19000001ef-1490284302.918161-268569705', '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 08:52:28 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 08:52:28 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 08:52:28 INFO - Using env: {'DISPLAY': ':0', 08:52:28 INFO - 'HOME': '/home/cltbld', 08:52:28 INFO - 'LANG': 'en_US.UTF-8', 08:52:28 INFO - 'LANGUAGE': 'en_US:en', 08:52:28 INFO - 'LOGNAME': 'cltbld', 08:52:28 INFO - 'MAIL': '/var/mail/cltbld', 08:52:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:52:28 INFO - 'MOZ_NO_REMOTE': '1', 08:52:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:52:28 INFO - 'NO_EM_RESTART': '1', 08:52:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:52:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:52:28 INFO - 'PWD': '/builds/slave/test', 08:52:28 INFO - 'SHELL': '/bin/bash', 08:52:28 INFO - 'SHLVL': '1', 08:52:28 INFO - 'TERM': 'linux', 08:52:28 INFO - 'TMOUT': '86400', 08:52:28 INFO - 'USER': 'cltbld', 08:52:28 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1490284302.918161-268569705', 08:52:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:52:28 INFO - '_': '/tools/buildbot/bin/python'} 08:52:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:52:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:52:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:52:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:52:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:52:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:52:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:52:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:52:30 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:52:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:52:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:52:30 INFO - Installing collected packages: mozsystemmonitor 08:52:30 INFO - Running setup.py install for mozsystemmonitor 08:52:31 INFO - Successfully installed mozsystemmonitor 08:52:31 INFO - Cleaning up... 08:52:31 INFO - Return code: 0 08:52:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:52:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:52:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:52:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:52:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:52:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x17c91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1806a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a67310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a64400>, '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 0x1a653b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a65840>, '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': 'eca2ab57546de7b41f256b19000001ef-1490284302.918161-268569705', '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 08:52:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:52:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:52:31 INFO - Using env: {'DISPLAY': ':0', 08:52:31 INFO - 'HOME': '/home/cltbld', 08:52:31 INFO - 'LANG': 'en_US.UTF-8', 08:52:31 INFO - 'LANGUAGE': 'en_US:en', 08:52:31 INFO - 'LOGNAME': 'cltbld', 08:52:31 INFO - 'MAIL': '/var/mail/cltbld', 08:52:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:52:31 INFO - 'MOZ_NO_REMOTE': '1', 08:52:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:52:31 INFO - 'NO_EM_RESTART': '1', 08:52:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:52:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:52:31 INFO - 'PWD': '/builds/slave/test', 08:52:31 INFO - 'SHELL': '/bin/bash', 08:52:31 INFO - 'SHLVL': '1', 08:52:31 INFO - 'TERM': 'linux', 08:52:31 INFO - 'TMOUT': '86400', 08:52:31 INFO - 'USER': 'cltbld', 08:52:31 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1490284302.918161-268569705', 08:52:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:52:31 INFO - '_': '/tools/buildbot/bin/python'} 08:52:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:52:31 INFO - Downloading/unpacking jsonschema==2.5.1 08:52:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:52:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:52:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:52:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:52:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:52:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:52:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:52:33 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:52:33 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 08:52:33 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 08:52:33 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 08:52:33 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 08:52:33 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 08:52:33 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 08:52:33 INFO - Downloading functools32-3.2.3-2.tar.gz 08:52:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:52:33 INFO - warning: no files found matching '*.txt' 08:52:33 INFO - no previously-included directories found matching 'build' 08:52:33 INFO - no previously-included directories found matching 'dist' 08:52:33 INFO - no previously-included directories found matching '.git*' 08:52:33 INFO - Installing collected packages: jsonschema, functools32 08:52:33 INFO - Running setup.py install for functools32 08:52:33 INFO - warning: no files found matching '*.txt' 08:52:33 INFO - no previously-included directories found matching 'build' 08:52:33 INFO - no previously-included directories found matching 'dist' 08:52:33 INFO - no previously-included directories found matching '.git*' 08:52:33 INFO - Successfully installed jsonschema functools32 08:52:33 INFO - Cleaning up... 08:52:33 INFO - Return code: 0 08:52:33 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:52:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:52:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:52:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:52:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:52:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:33 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 0x17c91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1806a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a67310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a64400>, '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 0x1a653b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a65840>, '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': 'eca2ab57546de7b41f256b19000001ef-1490284302.918161-268569705', '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 08:52:33 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 08:52:33 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 08:52:33 INFO - Using env: {'DISPLAY': ':0', 08:52:33 INFO - 'HOME': '/home/cltbld', 08:52:33 INFO - 'LANG': 'en_US.UTF-8', 08:52:33 INFO - 'LANGUAGE': 'en_US:en', 08:52:33 INFO - 'LOGNAME': 'cltbld', 08:52:33 INFO - 'MAIL': '/var/mail/cltbld', 08:52:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:52:33 INFO - 'MOZ_NO_REMOTE': '1', 08:52:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:52:33 INFO - 'NO_EM_RESTART': '1', 08:52:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:52:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:52:33 INFO - 'PWD': '/builds/slave/test', 08:52:33 INFO - 'SHELL': '/bin/bash', 08:52:33 INFO - 'SHLVL': '1', 08:52:33 INFO - 'TERM': 'linux', 08:52:33 INFO - 'TMOUT': '86400', 08:52:33 INFO - 'USER': 'cltbld', 08:52:33 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1490284302.918161-268569705', 08:52:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:52:33 INFO - '_': '/tools/buildbot/bin/python'} 08:52:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:52:34 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:52:34 INFO - Cleaning up... 08:52:34 INFO - Return code: 0 08:52:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:52:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:52:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:52:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:52:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:52:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x17c91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1806a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a67310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a64400>, '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 0x1a653b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a65840>, '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': 'eca2ab57546de7b41f256b19000001ef-1490284302.918161-268569705', '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 08:52:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:52:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:52:34 INFO - Using env: {'DISPLAY': ':0', 08:52:34 INFO - 'HOME': '/home/cltbld', 08:52:34 INFO - 'LANG': 'en_US.UTF-8', 08:52:34 INFO - 'LANGUAGE': 'en_US:en', 08:52:34 INFO - 'LOGNAME': 'cltbld', 08:52:34 INFO - 'MAIL': '/var/mail/cltbld', 08:52:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:52:34 INFO - 'MOZ_NO_REMOTE': '1', 08:52:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:52:34 INFO - 'NO_EM_RESTART': '1', 08:52:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:52:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:52:34 INFO - 'PWD': '/builds/slave/test', 08:52:34 INFO - 'SHELL': '/bin/bash', 08:52:34 INFO - 'SHLVL': '1', 08:52:34 INFO - 'TERM': 'linux', 08:52:34 INFO - 'TMOUT': '86400', 08:52:34 INFO - 'USER': 'cltbld', 08:52:34 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1490284302.918161-268569705', 08:52:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:52:34 INFO - '_': '/tools/buildbot/bin/python'} 08:52:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:52:34 INFO - Downloading/unpacking blobuploader==1.2.4 08:52:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:52:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:52:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:52:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:52:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:52:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:52:36 INFO - Downloading blobuploader-1.2.4.tar.gz 08:52:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:52:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:52:36 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 08:52:36 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 08:52:36 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 08:52:36 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 08:52:36 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 08:52:36 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 08:52:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:52:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:52:36 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 08:52:36 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 08:52:36 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 08:52:36 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 08:52:36 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 08:52:36 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 08:52:37 INFO - Downloading docopt-0.6.1.tar.gz 08:52:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:52:37 INFO - Installing collected packages: blobuploader, requests, docopt 08:52:37 INFO - Running setup.py install for blobuploader 08:52:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:52:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:52:37 INFO - Running setup.py install for requests 08:52:37 INFO - Running setup.py install for docopt 08:52:37 INFO - Successfully installed blobuploader requests docopt 08:52:37 INFO - Cleaning up... 08:52:37 INFO - Return code: 0 08:52:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:52:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:52:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:52:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:52:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:52:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:37 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 0x17c91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1806a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a67310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a64400>, '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 0x1a653b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a65840>, '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': 'eca2ab57546de7b41f256b19000001ef-1490284302.918161-268569705', '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 08:52:37 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 08:52:37 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 08:52:37 INFO - Using env: {'DISPLAY': ':0', 08:52:37 INFO - 'HOME': '/home/cltbld', 08:52:37 INFO - 'LANG': 'en_US.UTF-8', 08:52:37 INFO - 'LANGUAGE': 'en_US:en', 08:52:37 INFO - 'LOGNAME': 'cltbld', 08:52:37 INFO - 'MAIL': '/var/mail/cltbld', 08:52:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:52:37 INFO - 'MOZ_NO_REMOTE': '1', 08:52:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:52:37 INFO - 'NO_EM_RESTART': '1', 08:52:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:52:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:52:37 INFO - 'PWD': '/builds/slave/test', 08:52:37 INFO - 'SHELL': '/bin/bash', 08:52:37 INFO - 'SHLVL': '1', 08:52:37 INFO - 'TERM': 'linux', 08:52:37 INFO - 'TMOUT': '86400', 08:52:37 INFO - 'USER': 'cltbld', 08:52:37 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1490284302.918161-268569705', 08:52:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:52:37 INFO - '_': '/tools/buildbot/bin/python'} 08:52:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:52:38 INFO - Running setup.py (path:/tmp/pip-ibahCv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:52:38 INFO - Running setup.py (path:/tmp/pip-PAKCnv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:52:38 INFO - Running setup.py (path:/tmp/pip-31s_A0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:52:38 INFO - Running setup.py (path:/tmp/pip-4rYch6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:52:38 INFO - Running setup.py (path:/tmp/pip-mRA2iS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:52:38 INFO - Running setup.py (path:/tmp/pip-Xph8zi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:52:38 INFO - Running setup.py (path:/tmp/pip-pp5EJD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:52:38 INFO - Running setup.py (path:/tmp/pip-XGmdiQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:52:38 INFO - Running setup.py (path:/tmp/pip-Hu7f9F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:52:38 INFO - Running setup.py (path:/tmp/pip-Fj8uT3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:52:38 INFO - Running setup.py (path:/tmp/pip-ulf5hL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:52:38 INFO - Running setup.py (path:/tmp/pip-N8JUek-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:52:38 INFO - Running setup.py (path:/tmp/pip-Aktwfw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:52:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:52:39 INFO - Running setup.py (path:/tmp/pip-wWRBkB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:52:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:52:39 INFO - Running setup.py (path:/tmp/pip-KoxfvK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:52:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:52:39 INFO - Running setup.py (path:/tmp/pip-8vyueN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:52:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:52:39 INFO - Running setup.py (path:/tmp/pip-ipvy8v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:52:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:52:39 INFO - Running setup.py install for manifestparser 08:52:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:52:39 INFO - Running setup.py install for mozcrash 08:52:39 INFO - Running setup.py install for mozdebug 08:52:39 INFO - Running setup.py install for mozdevice 08:52:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:52:39 INFO - Running setup.py install for mozfile 08:52:39 INFO - Running setup.py install for mozhttpd 08:52:39 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:52:39 INFO - Running setup.py install for mozinfo 08:52:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:52:39 INFO - Running setup.py install for mozInstall 08:52:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:52:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:52:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:52:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:52:40 INFO - Running setup.py install for mozleak 08:52:40 INFO - Running setup.py install for mozlog 08:52:40 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:52:40 INFO - Running setup.py install for moznetwork 08:52:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:52:40 INFO - Running setup.py install for mozprocess 08:52:40 INFO - Running setup.py install for mozprofile 08:52:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:52:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:52:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:52:40 INFO - Running setup.py install for mozrunner 08:52:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:52:40 INFO - Running setup.py install for mozscreenshot 08:52:40 INFO - Running setup.py install for moztest 08:52:40 INFO - Running setup.py install for mozversion 08:52:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:52:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:52:41 INFO - Cleaning up... 08:52:41 INFO - Return code: 0 08:52:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:52:41 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')]} 08:52:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:52:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:41 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')]} 08:52:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:52:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:41 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 0x17c91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1806a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a67310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a64400>, '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 0x1a653b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a65840>, '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': 'eca2ab57546de7b41f256b19000001ef-1490284302.918161-268569705', '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 08:52:41 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 08:52:41 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 08:52:41 INFO - Using env: {'DISPLAY': ':0', 08:52:41 INFO - 'HOME': '/home/cltbld', 08:52:41 INFO - 'LANG': 'en_US.UTF-8', 08:52:41 INFO - 'LANGUAGE': 'en_US:en', 08:52:41 INFO - 'LOGNAME': 'cltbld', 08:52:41 INFO - 'MAIL': '/var/mail/cltbld', 08:52:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:52:41 INFO - 'MOZ_NO_REMOTE': '1', 08:52:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:52:41 INFO - 'NO_EM_RESTART': '1', 08:52:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:52:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:52:41 INFO - 'PWD': '/builds/slave/test', 08:52:41 INFO - 'SHELL': '/bin/bash', 08:52:41 INFO - 'SHLVL': '1', 08:52:41 INFO - 'TERM': 'linux', 08:52:41 INFO - 'TMOUT': '86400', 08:52:41 INFO - 'USER': 'cltbld', 08:52:41 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1490284302.918161-268569705', 08:52:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:52:41 INFO - '_': '/tools/buildbot/bin/python'} 08:52:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:52:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:52:41 INFO - Running setup.py (path:/tmp/pip-guQTqw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:52:41 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)) 08:52:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:52:41 INFO - Running setup.py (path:/tmp/pip-F0Rahz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:52:41 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)) 08:52:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:52:41 INFO - Running setup.py (path:/tmp/pip-lJ0Ze9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:52:41 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)) 08:52:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:52:41 INFO - Running setup.py (path:/tmp/pip-Artk4l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:52:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:52:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:52:41 INFO - Running setup.py (path:/tmp/pip-MBcDKB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:52:41 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)) 08:52:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:52:41 INFO - Running setup.py (path:/tmp/pip-Pj90Cm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:52:41 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)) 08:52:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:52:41 INFO - Running setup.py (path:/tmp/pip-Hl8EmE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:52:41 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)) 08:52:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:52:41 INFO - Running setup.py (path:/tmp/pip-_lrJ30-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:52:41 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)) 08:52:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:52:41 INFO - Running setup.py (path:/tmp/pip-wkLN_Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:52:41 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)) 08:52:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:52:41 INFO - Running setup.py (path:/tmp/pip-rmBZFi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:52:42 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)) 08:52:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:52:42 INFO - Running setup.py (path:/tmp/pip-toZ2RU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:52:42 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)) 08:52:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:52:42 INFO - Running setup.py (path:/tmp/pip-OduwN3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:52:42 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)) 08:52:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:52:42 INFO - Running setup.py (path:/tmp/pip-woqqNG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:52:42 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)) 08:52:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:52:42 INFO - Running setup.py (path:/tmp/pip-_yr6o1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:52:42 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)) 08:52:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:52:42 INFO - Running setup.py (path:/tmp/pip-VD6gGb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:52:42 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)) 08:52:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:52:42 INFO - Running setup.py (path:/tmp/pip-xPBdo0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:52:42 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)) 08:52:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:52:42 INFO - Running setup.py (path:/tmp/pip-T3uJZQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:52:42 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)) 08:52:42 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)) 08:52:42 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)) 08:52:42 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)) 08:52:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:52:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:52:42 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:52:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:52:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:52:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:52:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:52:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:52:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:52:44 INFO - Downloading blessings-1.6.tar.gz 08:52:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:52:44 INFO - Installing collected packages: blessings 08:52:44 INFO - Running setup.py install for blessings 08:52:44 INFO - Successfully installed blessings 08:52:44 INFO - Cleaning up... 08:52:44 INFO - Return code: 0 08:52:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:52:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:52:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:52:45 INFO - Reading from file tmpfile_stdout 08:52:45 INFO - Current package versions: 08:52:45 INFO - argparse == 1.2.1 08:52:45 INFO - blessings == 1.6 08:52:45 INFO - blobuploader == 1.2.4 08:52:45 INFO - docopt == 0.6.1 08:52:45 INFO - functools32 == 3.2.3-2 08:52:45 INFO - jsonschema == 2.5.1 08:52:45 INFO - manifestparser == 1.1 08:52:45 INFO - mozInstall == 1.12 08:52:45 INFO - mozcrash == 1.0 08:52:45 INFO - mozdebug == 0.1 08:52:45 INFO - mozdevice == 0.49 08:52:45 INFO - mozfile == 1.2 08:52:45 INFO - mozhttpd == 0.7 08:52:45 INFO - mozinfo == 0.9 08:52:45 INFO - mozleak == 0.1 08:52:45 INFO - mozlog == 3.4 08:52:45 INFO - moznetwork == 0.27 08:52:45 INFO - mozprocess == 0.25 08:52:45 INFO - mozprofile == 0.28 08:52:45 INFO - mozrunner == 6.13 08:52:45 INFO - mozscreenshot == 0.1 08:52:45 INFO - mozsystemmonitor == 0.3 08:52:45 INFO - moztest == 0.8 08:52:45 INFO - mozversion == 1.4 08:52:45 INFO - psutil == 3.1.1 08:52:45 INFO - requests == 1.2.3 08:52:45 INFO - wsgiref == 0.1.2 08:52:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:52:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:52:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:52:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:52:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:52:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:45 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 0x17c91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1806a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a67310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a64400>, '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 0x1a653b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a65840>, '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': 'eca2ab57546de7b41f256b19000001ef-1490284302.918161-268569705', '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 08:52:45 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 08:52:45 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 08:52:45 INFO - Using env: {'DISPLAY': ':0', 08:52:45 INFO - 'HOME': '/home/cltbld', 08:52:45 INFO - 'LANG': 'en_US.UTF-8', 08:52:45 INFO - 'LANGUAGE': 'en_US:en', 08:52:45 INFO - 'LOGNAME': 'cltbld', 08:52:45 INFO - 'MAIL': '/var/mail/cltbld', 08:52:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:52:45 INFO - 'MOZ_NO_REMOTE': '1', 08:52:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:52:45 INFO - 'NO_EM_RESTART': '1', 08:52:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:52:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:52:45 INFO - 'PWD': '/builds/slave/test', 08:52:45 INFO - 'SHELL': '/bin/bash', 08:52:45 INFO - 'SHLVL': '1', 08:52:45 INFO - 'TERM': 'linux', 08:52:45 INFO - 'TMOUT': '86400', 08:52:45 INFO - 'USER': 'cltbld', 08:52:45 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1490284302.918161-268569705', 08:52:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:52:45 INFO - '_': '/tools/buildbot/bin/python'} 08:52:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:52:45 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)) 08:52:45 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)) 08:52:45 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)) 08:52:45 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)) 08:52:45 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)) 08:52:45 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)) 08:52:45 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)) 08:52:45 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)) 08:52:45 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)) 08:52:45 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)) 08:52:45 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)) 08:52:45 INFO - Cleaning up... 08:52:45 INFO - Return code: 0 08:52:45 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:52:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:52:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:52:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:52:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:52:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:45 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 0x17c91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1806a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a67310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a64400>, '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 0x1a653b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a65840>, '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': 'eca2ab57546de7b41f256b19000001ef-1490284302.918161-268569705', '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 08:52:45 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 08:52:45 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 08:52:45 INFO - Using env: {'DISPLAY': ':0', 08:52:45 INFO - 'HOME': '/home/cltbld', 08:52:45 INFO - 'LANG': 'en_US.UTF-8', 08:52:45 INFO - 'LANGUAGE': 'en_US:en', 08:52:45 INFO - 'LOGNAME': 'cltbld', 08:52:45 INFO - 'MAIL': '/var/mail/cltbld', 08:52:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:52:45 INFO - 'MOZ_NO_REMOTE': '1', 08:52:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:52:45 INFO - 'NO_EM_RESTART': '1', 08:52:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:52:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:52:45 INFO - 'PWD': '/builds/slave/test', 08:52:45 INFO - 'SHELL': '/bin/bash', 08:52:45 INFO - 'SHLVL': '1', 08:52:45 INFO - 'TERM': 'linux', 08:52:45 INFO - 'TMOUT': '86400', 08:52:45 INFO - 'USER': 'cltbld', 08:52:45 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1490284302.918161-268569705', 08:52:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:52:45 INFO - '_': '/tools/buildbot/bin/python'} 08:52:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:52:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:52:45 INFO - Cleaning up... 08:52:45 INFO - Return code: 0 08:52:45 INFO - Running post-action listener: _resource_record_post_action 08:52:45 INFO - Running post-action listener: _start_resource_monitoring 08:52:45 INFO - Starting resource monitoring. 08:52:45 INFO - [mozharness: 2017-03-23 15:52:45.634026Z] Finished create-virtualenv step (success) 08:52:45 INFO - [mozharness: 2017-03-23 15:52:45.634970Z] Running install step. 08:52:45 INFO - Running pre-action listener: _resource_record_pre_action 08:52:45 INFO - Running main action method: install 08:52:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:52:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:52:45 INFO - Reading from file tmpfile_stdout 08:52:45 INFO - Detecting whether we're running mozinstall >=1.0... 08:52:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:52:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:52:45 INFO - Reading from file tmpfile_stdout 08:52:45 INFO - Output received: 08:52:45 INFO - Usage: mozinstall [options] installer 08:52:45 INFO - Options: 08:52:45 INFO - -h, --help show this help message and exit 08:52:45 INFO - -d DEST, --destination=DEST 08:52:45 INFO - Directory to install application into. [default: 08:52:45 INFO - "/builds/slave/test"] 08:52:45 INFO - --app=APP Application being installed. [default: firefox] 08:52:45 INFO - mkdir: /builds/slave/test/build/application 08:52:45 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'] 08:52:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 08:53:02 INFO - Reading from file tmpfile_stdout 08:53:02 INFO - Output received: 08:53:02 INFO - /builds/slave/test/build/application/firefox/firefox 08:53:02 INFO - Running post-action listener: _resource_record_post_action 08:53:02 INFO - [mozharness: 2017-03-23 15:53:02.793617Z] Finished install step (success) 08:53:02 INFO - [mozharness: 2017-03-23 15:53:02.793958Z] Running run-tests step. 08:53:02 INFO - Running pre-action listener: _resource_record_pre_action 08:53:02 INFO - Running main action method: run_tests 08:53:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:53:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:53:02 INFO - Python 2.7.3 08:53:02 INFO - Return code: 0 08:53:02 INFO - grabbing minidump binary from tooltool 08:53:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:02 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 0x1a64400>, '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 0x1a653b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a65840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:53:02 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 08:53:02 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 08:53:02 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 08:53:02 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:53:02 INFO - Return code: 0 08:53:02 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:53:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:53:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:53:02 INFO - ENV: RUST_BACKTRACE is now 1 08:53:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:53:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:53:02 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/LyO05s08SuOSAGNFWIebQw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-010', '--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 08:53:02 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/LyO05s08SuOSAGNFWIebQw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-010 --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 08:53:02 INFO - Using env: {'DISPLAY': ':0', 08:53:02 INFO - 'HOME': '/home/cltbld', 08:53:02 INFO - 'LANG': 'en_US.UTF-8', 08:53:02 INFO - 'LANGUAGE': 'en_US:en', 08:53:02 INFO - 'LOGNAME': 'cltbld', 08:53:02 INFO - 'MAIL': '/var/mail/cltbld', 08:53:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:53:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:53:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:02 INFO - 'MOZ_NO_REMOTE': '1', 08:53:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:53:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:53:02 INFO - 'NO_EM_RESTART': '1', 08:53:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:53:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:02 INFO - 'PWD': '/builds/slave/test', 08:53:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:53:02 INFO - 'RUST_BACKTRACE': '1', 08:53:02 INFO - 'SHELL': '/bin/bash', 08:53:02 INFO - 'SHLVL': '1', 08:53:02 INFO - 'TERM': 'linux', 08:53:02 INFO - 'TMOUT': '86400', 08:53:02 INFO - 'USER': 'cltbld', 08:53:02 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1490284302.918161-268569705', 08:53:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:02 INFO - '_': '/tools/buildbot/bin/python'} 08:53:02 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/LyO05s08SuOSAGNFWIebQw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-010', '--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 08:53:03 INFO - mozversion application_buildid: 20170323144904 08:53:03 INFO - mozversion application_changeset: b0494b853b51c13deef16cb463cb30f3e4c0e1d0 08:53:03 INFO - mozversion application_display_name: Nightly 08:53:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:53:03 INFO - mozversion application_name: Firefox 08:53:03 INFO - mozversion application_remotingname: firefox 08:53:03 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:53:03 INFO - mozversion application_vendor: Mozilla 08:53:03 INFO - mozversion application_version: 55.0a1 08:53:03 INFO - mozversion platform_buildid: 20170323144904 08:53:03 INFO - mozversion platform_changeset: b0494b853b51c13deef16cb463cb30f3e4c0e1d0 08:53:03 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:53:03 INFO - mozversion platform_version: 55.0a1 08:53:03 INFO - using testdate: 1490284383 08:53:03 INFO - actual date: 1490284383 08:53:03 INFO - starting webserver on 'localhost:57104' 08:53:03 INFO - SUITE-START | Running 2 tests 08:53:03 INFO - TEST-START | dromaeo_css 08:53:03 INFO - operating with platform_type : linux_ 08:53:03 INFO - Initialising browser for dromaeo_css test... 08:53:03 INFO - TEST-INFO | started process 3311 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbETZdb/profile http://localhost:57104/getInfo.html) 08:53:11 INFO - PID 3311 | __metrics Screen width/height:1600/1200 08:53:11 INFO - PID 3311 | colorDepth:24 08:53:11 INFO - PID 3311 | Browser inner width/height: 1024/697 08:53:11 INFO - PID 3311 | __metrics 08:53:12 INFO - PID 3311 | 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" 08:53:12 INFO - PID 3311 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:53:13 INFO - TEST-INFO | 3311: exit 0 08:53:13 INFO - Browser initialized. 08:53:13 INFO - Running cycle 1/1 for dromaeo_css test... 08:53:13 INFO - TEST-INFO | started process 3406 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbETZdb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:53:25 INFO - PID 3406 | RSS: Main: 145575936 08:53:25 INFO - PID 3406 | 08:56:28 INFO - PID 3406 | Cycle 1(1): loaded http://localhost:57104/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57104/tests/dromaeo/cssquery-ext.html) 08:56:29 INFO - PID 3406 | RSS: Main: 287223808 08:56:29 INFO - PID 3406 | 08:58:15 INFO - PID 3406 | ************************* 08:58:15 INFO - PID 3406 | A coding exception was thrown and uncaught in a Task. 08:58:15 INFO - PID 3406 | 08:58:15 ERROR - PID 3406 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:58:15 INFO - PID 3406 | Full stack: 08:58:15 INFO - PID 3406 | ************************* 08:59:32 INFO - PID 3406 | Cycle 1(1): loaded http://localhost:57104/tests/dromaeo/cssquery-ext.html (next: http://localhost:57104/tests/dromaeo/cssquery-jquery.html) 08:59:32 INFO - PID 3406 | RSS: Main: 231600128 08:59:32 INFO - PID 3406 | 09:02:34 INFO - PID 3406 | Cycle 1(1): loaded http://localhost:57104/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57104/tests/dromaeo/cssquery-mootools.html) 09:02:35 INFO - PID 3406 | RSS: Main: 209547264 09:02:35 INFO - PID 3406 | 09:05:37 INFO - PID 3406 | Cycle 1(1): loaded http://localhost:57104/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57104/tests/dromaeo/cssquery-prototype.html) 09:05:37 INFO - PID 3406 | RSS: Main: 176349184 09:05:37 INFO - PID 3406 | 09:08:40 INFO - PID 3406 | Cycle 1(1): loaded http://localhost:57104/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57104/tests/dromaeo/cssquery-yui.html) 09:08:41 INFO - PID 3406 | RSS: Main: 185683968 09:08:41 INFO - PID 3406 | 09:11:43 INFO - PID 3406 | Cycle 1(1): loaded http://localhost:57104/tests/dromaeo/cssquery-yui.html (next: http://localhost:57104/tests/dromaeo/cssquery-dojo.html) 09:11:43 INFO - PID 3406 | RSS: Main: 277598208 09:11:43 INFO - PID 3406 | 09:11:43 INFO - PID 3406 | __start_tp_report 09:11:44 INFO - PID 3406 | _x_x_mozilla_page_load 09:11:44 INFO - PID 3406 | _x_x_mozilla_page_load_details 09:11:44 INFO - PID 3406 | |i|pagename|runs| 09:11:44 INFO - PID 3406 | |0;dojo.html;3319.9376199616117;3331.7334613230946;3350.514175444562;3355.8489867955946;3376.6960973512378;5562.9443705562935;5655.20827084208;5710.486056254937;5734;5741.684207368593;5714.542836573075;5748.310202775673;5756;5799.565032622549;5837.270341207349;4949.529794669501;5004.12427825131;5028.4971502849785;5110.591152707793;5125.256837758523;5118.9744051279695;5152.098382783017;5153.149730294497;5168.689878607295;5199.974000129994;4999.500049995007;5030.798768049311;5031.798728050873;5077.162268225738;5079.517445842639;5028.1200789862;5072.315237442935;5081.085404627165;5126.769295381717;5138.7687554059785;4968.080905032581;5014.448410674823;5025.0954828130925;5043.52086551777;5073.695578265316;5560.471755183251;5614.859628509279;5715.199872017921;5715.771369145229;5722.799702010451;6105.267367915834;6139.324674285825;6159.476444502223;6172.814815555495;6182.567220294582;6200.751969921198;6217.595856269328;6225.688715564203;6233.096201050867;6233.252009758812;5714.2571465710025;5751.654900705971;5754.884902301972;5761.452661997104;5811.331696854873;6199.194104766351;6256.968715156395;6285.465735412541;6297.811065668037;6337.081123237105;5930.377310382348;5985.132155837379;5997.490213331916;6000;6137.0794380842335;6134.228053882901;6142.621558143092;6147.569670123048;6153.815385538441;6166.167567378437;6065.090236464477;6141.631502109798;6142.815715528542;6161.84595385119;6166.660833654102;6048.153258543807;6103.572749907464;6105.436769732072;6123.724432400552;6129.908051379233;4674.766261686902;4696.835610753607;4710.4347478302825;4716;4719;4192.413062171298;4193.454850869368;4198.1393814268;4206.390073439396;4228.682848786353;5752.309722833287;5813.418658134237;5834.183214349994;5858.8535286617325;5861.443162899518;6145.740123274718;6165.84585385369;6178.227721534809;6181.134641150243;6197.597156184924;5792.855178620568;5794.710264486759;5799.88400231993;5803.506701930299;5810.88378232433;5168.198929165986;5183.352080989876;5235.031519168966;5238.266642670029;5239.266502689627;6394.360563943568;6401.647909364937;6476.967615161894;6481.578697384655;6483.578567393104;6176.629402235881;6179.443850053517;6182.227221597301;6185.474234690102;6191.25704915413;6472.190976127984;6476.190476190476;6476.5466417350335;6484.383983521558;6490.058941453557;5937.020391635505;6026.789062382795;6055.848603784764;6064.211652485149;6067.635941843503;6101.762223876893;6122.886104953835;6136.733532366248;6143.972489078683;6171.907421388593;5994.340622531431;6020.458158765732;6020.578559500968;6063.666498342457;6081.908771368345;5994.67029313392;6022.277326720821;6033.698315084316;6046.969765151146;6080.90878636812;6027.186329845415;6038.4867286279405;6045.909311360421;6051.243594550681;6051.576389652858;5956.731947062306;5967.075103358986;5969.432903874125;6029.246344206974;6029.758809647565;5824.621399609012;5832.3292821326095;5851.771128063155;5859.619124756877;5889 09:11:44 INFO - PID 3406 | |1;ext.html;603.5262319079524;657.2343220148528;674.8886433738431;1121.3496172220114;1124.3029321820468;29832.1050368489;37675;38590.61409385909;38624.80687596565;38685.613143868555;39660;40136.19727605446;40153.598464015355;40263.79868100663;40354.14864844596;6042.57701960863;6174.44430001302;7968.083670377909;8137.9593102034405;8145.104038555754;7877.921220787776;8051.114377418479;8065.2741253287195;8065.637046332899;8067.878981815277;8024.478408903403;8300.045494768088;8318.83362332756;8321.83356332873;8322.583870806466;8194.426390152692;8254.339652827759;8269.83460330796;8270.710525131592;8286.088530261666;8089.352851771844;8144.389170812154;8161.5919204039565;8162.347012239066;8165.550894700789;40258.597414025775;40482.595174048176;40491.59508404908;40522.39216411756;40581.59418405837;2973.44991176633;2995.056557184502;3002.4145291668133;3004.2339203503184;3028.0915725282325;28028.579571306447;28100.297492562848;28276.57585136204;28445.85777071122;29277.56083658747;3486.093615659947;3510.227749895019;3542.6457354264367;3548.6628770266784;3596.1009747563107;217752;218149;218386;220132;220191;50399;51112.74443627758;51130.74434627803;51136.74431627818;51189.48810511847;53736.46263537393;53878;54017.459825401245;54042;54153.1877021845;14005.719885602435;14210;14266.714665706628;14268;14279.714405711828;9787;9923.354981926152;10671;11364.318140911571;11418.086553075735;13367.732645347038;13439.731205375838;13561.050726449053;13569.796453053015;13689.383977721027;5062.974685126551;5069.746512674352;5111.5655169310285;5226.738663066832;5227.973860130675;1010.3584224017654;1015.9898401016043;1027.6968294353185;1038.2628333882876;1050.558765318568;25318.493630127294;25532.617010744852;25762.812872467523;26034.73965260361;26078.608820867703;1127.548980407827;1127.6335191062938;1142.2974870454777;1152.6484422251126;1177.7055736065984;13055.151415157985;13187.868121318665;13382.799258011137;13472.12431191989;13524;288676;288831;288933;289575;289988;55599.77600895919;55710;55716.72141639266;55793;56390.71804640951;1364.515596963064;1450.9056911300731;1457.0310743355762;1461.3423959218394;1493.8804895608325;23609.645855312527;24010.63984040206;24563.263102106965;24687.259382218563;24717.38206544779;39115.804420977714;39754.614723116705;39780.801095994335;39852.20295594014;39852.2029559413;283706;283902;284342;284546;284631;26017.6097358536;26134.86932565325;32579.69681212725;34596;34720.131996700286;39142.80428597839;40149.799251003555;40256;40480.3927941087;40555.79722101371;30892.073237802902;30952.380952380376;31023.689763102982;31183.53224701586;31320.686793131776;30029.54955675623;30452.390952181275;30894.69105308918;31349.686503134675;31483.212919677193;35071.824640876635;35150;35162;35776;36085.27829443449;29667.554986674786;29693.40613187681;29744;30148.698513015468;30169;23596.410089747893;24844.503109937337;24872.751272487767;25092.874535627205;25212.621810673223 09:11:44 INFO - PID 3406 | |2;jquery.html;430.25565771215776;430.92286871509737;431.1656943813721;436.67904090493494;445.26753490508116;19587;19619.705704414428;20008.69986950197;20226.494337641565;20250.59498810025;19779.406617801455;20740.585188296223;20813;21075;21361.67957480639;5807.477327040566;6014.248218972629;6018.15745795589;6019.247594050744;6019.578629495915;5786.479216870481;5894.793682221123;5949.8215053548465;5980.073088671263;5986.431289027535;5930.673812940287;5945.762169513214;5955.464008239258;5956.553258505608;5969.22400087988;5880.147378630118;5897.941020589782;6038.939610603926;6048.879022419571;6072.392760723892;5452.372977107644;5502.5597952164135;5511.200875872976;5512.834614961557;5516.862078448071;25151.119710810217;25187.87406062958;25365.365865853502;25390.746092539022;25473.61789573158;1924.134139637155;2013.2047841102828;2017.0923084611836;2023.494126468383;2041.4181958142008;14880.330385132695;15381.077135371914;15571.844281557265;16117.597060073474;16202.594935126599;1853.1753369750465;1863.5341164708823;1866.4773863318292;1867.1691097461635;1904.266857259951;70640;84681;84877.57561212216;85626.14373856244;107248.46375768149;11864.703382415508;11916.106292028133;11930.701732456584;12164.289891582976;12215.938920305342;14965;15350.07899526032;15851.920740396225;16307.347706091861;16409.179541022902;7975.282224599814;8092.71675491355;8108.756737297773;8301.6679332826;8305.875411868827;7607.923920760722;7650.19672934345;7759.844803104019;7874.527528348317;7923.12845586985;7568.508046976929;7820.871651339509;7861.567613781182;7928.246816552418;7975.282224599814;2059.217497351027;2063.4119276006268;2093.6964140199584;2169.43594665385;2319.840585313653;987.3236832769576;993.8509223616371;995.3132338686428;1005.4168582222294;1026.0867827633413;20026.399208023784;20222.696659550118;20315.288964886455;20402.69395959062;20455.488612784502;1019.9490025498695;1040.328987802863;1044.1125043712784;1044.9738756531146;1058.6771034834296;10243.865911189758;11190.888091119148;11242.325460472235;11279.32324060559;11286.774264514665;551420;553752;558794;559614;560072;73578.26421735821;94795;99190.52833150805;100002.4999874996;104109;1170.1628443344039;1176.5470293936896;1204.5121725701106;1213.666241783499;1215.9878401215874;16084.919575402047;16853.24160412809;17101.23044463027;17405.21516557345;17466.388676396266;46886.30432938615;47565.76217118892;47859;47896;48969;62249.37750622436;68906;70697.64651176712;71271.64364178145;71448.64135189986;9131.452112873249;9162.312826537904;9180.586873590571;9227.723168304961;9300.11648893354;14011.439542418597;14629.780553291923;14633;14728.77906831377;14970.251487425803;11802.114841386758;11905.761884762429;12149.448615790783;12211.938940305241;12271.141020128505;10841.512131953923;10972.23194376418;11045.39250341239;11048;11216.383098929637;11377;11972.820407693718;12212.08409369283;12341.814872776737;12497.93751031239;8949.418287811272;9077.137671921157;9091.772705682411;9356.812863742824;9406.576704048472;11632.18574699763;11960.401979901144;12052.09609279325;12135.211211271238;12205.877941220475 09:11:44 INFO - PID 3406 | |3;mootools.html;1350.3045931345357;1398.790181472779;1408.8027676125341;1418.6098822823728;1424.5085445521293;10353.585856565738;10748.623798167046;10759.78480430392;10762;10766.300190487613;10469.738256543591;10472.947635261831;10668.253222274425;10719.410432426213;10739.570417183322;2130.1160018592263;2145.313499680103;2157.385145233609;2161.8270538356974;2163.740351157863;1991.5618563915916;2263.1187513414493;2329.4176455886027;2364.0189321431585;2404.9398765030837;2007.8694884832512;2231.0852550454315;2308.3536609749212;2321.164380822911;2323.546908352872;2216.4015715756655;2310.1336998625516;2318.188633978111;2321.0599707118645;2324.500232450023;2239.4177513846516;2257.604919139144;2266.388075219698;2276.64711969645;2278.441781763462;10999.560017599286;11093.3898635575;11095.72260693489;11102.056325212367;11102.111831053497;6281.59169653971;6460.160179176724;6482.546221764478;6496.707648155844;6498.1552398188405;3797.2974999625158;3984.063745019918;4174.624283814472;4182.790860456965;4220.9155816883795;1365.7669710024181;1415.716856628668;1434.7560914644534;1465.4065103633045;1493.126520985225;15663.608409789733;15965.521034368987;16087.839121608868;16095.114768687718;16490.093044882527;5203.609729270244;5315.362156541162;5367.677939323653;5401.270828438111;5451.454854514517;12373.62879113628;12414.62756117318;12417.31704756247;12418.627441176599;12427.192232504858;6289.308176100625;6294.937050629435;6314.400131987454;6316.178896743486;6317.747290108436;5684.8294551163535;5722.484976352148;5745.425457454221;5793.04414771558;5925.851853703605;4014.8568249149835;4071.592840715964;4080.6327430531396;4088.0801819590947;4155.231282212801;1029.1663752360462;1237.189640785287;1241.2304371289677;1247.7754004279136;1260.6281147061638;694.2259380790382;800.931920786728;829.3738227638095;832.633641197871;844.0335815491228;5608.355039170548;5931.555133364928;5938.584299099108;6048.425399587032;6106.603070800384;912.808310254854;926.7127190570944;933.5285680731323;947.7725345917069;950.296780382512;3833.4058220975794;3868.6905047596124;3874.903127421837;3903.316919539069;3913.9021524461546;14257.92871035659;14815.555533334018;14816.3332650031;14820;14825.92587037058;13679.521216757366;13972.441102355995;14671;14683.92658036703;14689;10022.548985295532;10036.096751292418;10235.641752538626;10296.794064118676;10300.330478518872;6190.535709821691;6298.496120310456;6444.387783160592;6792.87917493608;6857.931420685729;12938;13007.804882926954;13125.803112953123;13264.734705306033;13361.933190333986;12569.685757856127;12781.424835882593;13099.934500327437;13567.321633918067;13655.317234138054;8926.152015558511;9043.864342034743;9104.863427048467;9115.680951166678;9188.219001384958;9908.455034973145;10094.697159085239;10100.393976361442;10104.545295461576;10122.645707400206;8007.719729809429;8032.919670803451;8093.312068474245;8118.959405202936;8407.789805254917;8118.959405202936;8175.468594541336;8194.426390152632;8206.384521161057;8406.873896891673;7328.450366222448;7406.44451666138;7457.40340772726;7473.476856619985;7484.401247900264;5310.044192045314;5321.3348331458565;5340.172273297645;5394.271773310553;5543.52880005204;5414.972925135349;5421.1055175895535;5456.563474922076;5484.040292948798;5553.8333849984565 09:11:44 INFO - PID 3406 | |4;prototype.html;416.5418040155828;419.8446574767336;425.823283337415;427.62796367160576;431.53807528052465;5824.737886795094;5958.136070269814;5987.520998320135;5995.190649262348;6012.218411606491;5776.653400795955;5856.243127061875;5861.824145275638;5861.8569378971115;5876.4417380348905;4487.439070116236;4489.663275254353;4549.431321084864;4622.930656040162;4636.142313671965;4622.9768851155695;4628.1669299526075;4634.189016922042;4641.280601506772;4642.259152817694;4589.632829373659;4602.631789456836;4608.216603177451;4611.861644150681;4626.398568186131;4495.550444955495;4587.3577699121815;4596.4254468191475;4607.447106347243;4618.2610782275015;4150.958490415087;4151.4395556599775;4159.9376009359885;4164.583541645841;4168.7707176105305;5701.657900525982;5741.971290143522;5751.166080918243;5754.568407369422;5768.221290125821;5273.6572122811895;5454.154606036071;5463.836084917459;5478.671279723229;5497.45025497451;6246.500279977591;6264.718087686065;6267.310595834455;6271.400792797853;6273.717682704244;4576.199165146119;4725.763711814396;4728.172569800306;4729.219678753002;4742.264948932921;6170.457385653586;6389.201349831271;6537.93462065378;6553.901691474632;6623.714818387649;4708.95291047092;4822.445418776885;4856.8300109495995;4865.683730557503;4882.5605695487575;4887.511248875155;4890.997345544155;4915.48387419323;4922.8769280768265;4925;5036.395632524121;5115.7442127893455;5450.591205659592;5512.5589952803675;5535.086710692771;6214.782482613087;6236.18929539157;6240;6242.968785156136;6302.275238347557;1686.215909602041;1700.115939711368;1709.734991076385;1714.3314107498086;1728.2222999650207;1177.4466000979526;1233.0751936047964;1235.1045492018216;1236.1223531292703;1237.0783766094137;5947.851303717355;5951.880962380728;5973.671448070398;6021.969890150609;6026.397360264026;6278;6332.208473940757;6332.602940739073;6378.617282962944;6399.55203135776;5903.26209223847;5905.143754155623;5914.9112763308585;5960.523158147338;5982.1325907744;3325.235195904907;3381.847816848248;3382.915427114342;3385.712214461749;3392.8133952632347;6410.1987251593555;6572.34276572339;6644.368784965421;6659.633720145439;6661;6482.611043337415;6494;6501.739930402731;6511.153550038464;6536.411722944863;6420.261669907927;6511.73953041873;6537.378948999837;6583.275839657634;6591.242007169237;6301.590396624205;6316.842078948063;6317.557770955989;6322.525810564134;6373.8725225496155;6053.757849685963;6263.27972283175;6290.654014029273;6297.103332466933;6312.116303717575;6282.403171698681;6401.743930242738;6408.423241908251;6613.437857782143;6652.767153149616;4760.428748550196;4769.8313913091515;4782.378290822171;4804.783784729765;4814.277858321279;6165.5375846810775;6270.968645156745;6333.081703153109;6370.713317900798;6379.776707815204;6138.140660307471;6156.692165391623;6158.507319414346;6159.5688301818445;6162.168107305636;6138.263408391022;6155.753769849156;6156.846078848064;6170.167027451236;6178.66017369049;5939.821805345847;6131.601445906002;6161.383861613803;6177.722002509988;6212.689365531795;6111.297200821962;6114.785982490592;6143.692815359124;6204.13142160089;6213;5820.650760954356;6009.969950150221;6034.9094763577705;6040.2751669798145;6042.90935635957 09:11:44 INFO - PID 3406 | |5;yui.html;760.7603604864468;766.8044648614605;769.6805825582384;787.5156778581174;787.9290863822255;16176.27206775695;16375.83624163758;16394.344226230936;16396.67206655866;16429.34282628695;13920.234387108705;13977.23125228112;13979.370928308224;13987.090839095452;13999.860001399982;816.5508970066469;818.1450384348341;826.5949684654536;838.714836955435;841.9663213471484;832.81261716114;838.6896848166188;838.7986883148031;839.5088873009297;839.9034111077212;890.5191196753746;892.0588778838323;892.5091199840094;892.9330300227476;893.1916615462984;877.1886005444937;881.8060026794127;881.9250363719092;883.7746374674481;884.2925659472396;648.6853875870199;650.8340373204802;653.2846533046306;655.6787174284614;656.8916128838783;17135;17355.21901514435;17357.566060848454;17628.647427051514;17886.642267154715;16081;16308.836911630851;16511.422100226555;16524.75212871808;16569.6686066278;11751.588694395741;12463.563775267912;12579.811302830465;12764.361781910868;13294.135881167693;3079.491883839164;3090.8300043497597;3101.147184524274;3175.523671449278;3183.5383869338816;84839;84865;87204.56397717971;87232.12767872367;87579.56210218908;12587.622371328875;12620.30588317633;12662.430190641444;12889.677758056123;12917.935410323076;25317.62023569648;25401.61897571538;25495.872520637276;25515.448455154336;25531.36171595725;7830.451868369273;7912.367010639135;7980.720674776355;8043.436959412785;8129.065157506844;4093.8976525586504;4164.875053748392;4180.498340199135;4182.53992060873;4182.895427614334;4173.248815213291;4182.477190351206;4218.557051509547;4237.555056719;4278.122984788111;723.3345322303494;734.7061175529831;744.9329560339595;749.9287567681062;758.4577027425418;588.7497813429276;590.4892268188072;605.9272887253558;608.0875646093012;619.8419403052283;10551.736206594896;10900.672979810619;11140.871976736673;11228.606998755004;11241.775164496665;622.2004723929729;629.6411045703904;632.8449529865212;635.3646353646353;637.1875858280729;4613.861584152481;4879.463259041502;4882.2432522959;4899.1426500362295;4914.69276943383;57528;59895.70052149799;76892;77309.61345193238;80569.19430805734;58924.70537647284;58931;62762.372376275656;72046;74202.6289868558;57356.71321643365;57849.42150578524;58066;58094.4190558089;58118.41881581215;11032.503537340679;11494.655160345203;11524;11559.826602600799;11695.532178712756;29745;30352.544711828898;30656.233594160323;31520.842395787873;33265;52937.73531132474;54402.727986359816;54877.72561137169;55578.44421555894;56256.718716406154;14112.717745645235;14206.289685515889;14469.85530144685;14567.92716036413;14656.633584160054;15063.849361506684;15534.1456219909;15836.208189590705;15999;16138.435154769526;13756.518521851687;13881.236531990837;14239.64400889986;14276.357563909442;14815.111093334435;14326;14715.190664513126;15085;15599.610009749847;15638.29627666735;14171.716565668836;14699.779503307673;14980.925095374454;15791.68416631684;15850.128242946748;10065.446400447752;10380.740481488023;10734.946325268324;11006.724831879266;11074.335539867634;11192.328460292083;11643.53425862956;11688.590899318484;11940.223885447418;12062.51749929993 09:11:44 INFO - PID 3406 | __end_tp_report 09:11:44 INFO - PID 3406 | __start_cc_report 09:11:44 INFO - PID 3406 | _x_x_mozilla_cycle_collect,1457 09:11:44 INFO - PID 3406 | __end_cc_report 09:11:44 INFO - PID 3406 | __startTimestamp1490285503998__endTimestamp 09:11:44 INFO - PID 3406 | 09:11:44 INFO - PID 3406 | ------- Summary: start ------- 09:11:44 INFO - PID 3406 | Number of tests: 6 09:11:44 INFO - PID 3406 | 09:11:44 INFO - PID 3406 | [#0] dojo.html Cycles:165 Average:5690.33 Median:5956.73 stddev:673.87 (11.3%) stddev-sans-first:649.78 09:11:44 INFO - PID 3406 | Values: 3319.9 3331.7 3350.5 3355.8 3376.7 5562.9 5655.2 5710.5 5734.0 5741.7 5714.5 5748.3 5756.0 5799.6 5837.3 4949.5 5004.1 5028.5 5110.6 5125.3 5119.0 5152.1 5153.1 5168.7 5200.0 4999.5 5030.8 5031.8 5077.2 5079.5 5028.1 5072.3 5081.1 5126.8 5138.8 4968.1 5014.4 5025.1 5043.5 5073.7 5560.5 5614.9 5715.2 5715.8 5722.8 6105.3 6139.3 6159.5 6172.8 6182.6 6200.8 6217.6 6225.7 6233.1 6233.3 5714.3 5751.7 5754.9 5761.5 5811.3 6199.2 6257.0 6285.5 6297.8 6337.1 5930.4 5985.1 5997.5 6000.0 6137.1 6134.2 6142.6 6147.6 6153.8 6166.2 6065.1 6141.6 6142.8 6161.8 6166.7 6048.2 6103.6 6105.4 6123.7 6129.9 4674.8 4696.8 4710.4 4716.0 4719.0 4192.4 4193.5 4198.1 4206.4 4228.7 5752.3 5813.4 5834.2 5858.9 5861.4 6145.7 6165.8 6178.2 6181.1 6197.6 5792.9 5794.7 5799.9 5803.5 5810.9 5168.2 5183.4 5235.0 5238.3 5239.3 6394.4 6401.6 6477.0 6481.6 6483.6 6176.6 6179.4 6182.2 6185.5 6191.3 6472.2 6476.2 6476.5 6484.4 6490.1 5937.0 6026.8 6055.8 6064.2 6067.6 6101.8 6122.9 6136.7 6144.0 6171.9 5994.3 6020.5 6020.6 6063.7 6081.9 5994.7 6022.3 6033.7 6047.0 6080.9 6027.2 6038.5 6045.9 6051.2 6051.6 5956.7 5967.1 5969.4 6029.2 6029.8 5824.6 5832.3 5851.8 5859.6 5889.0 09:11:44 INFO - PID 3406 | 09:11:44 INFO - PID 3406 | [#1] ext.html Cycles:180 Average:42201.96 Median:31335.19 stddev:69597.49 (222.1%) stddev-sans-first:69722.64 09:11:44 INFO - PID 3406 | Values: 603.5 657.2 674.9 1121.3 1124.3 29832.1 37675.0 38590.6 38624.8 38685.6 39660.0 40136.2 40153.6 40263.8 40354.1 6042.6 6174.4 7968.1 8138.0 8145.1 7877.9 8051.1 8065.3 8065.6 8067.9 8024.5 8300.0 8318.8 8321.8 8322.6 8194.4 8254.3 8269.8 8270.7 8286.1 8089.4 8144.4 8161.6 8162.3 8165.6 40258.6 40482.6 40491.6 40522.4 40581.6 2973.4 2995.1 3002.4 3004.2 3028.1 28028.6 28100.3 28276.6 28445.9 29277.6 3486.1 3510.2 3542.6 3548.7 3596.1 217752.0 218149.0 218386.0 220132.0 220191.0 50399.0 51112.7 51130.7 51136.7 51189.5 53736.5 53878.0 54017.5 54042.0 54153.2 14005.7 14210.0 14266.7 14268.0 14279.7 9787.0 9923.4 10671.0 11364.3 11418.1 13367.7 13439.7 13561.1 13569.8 13689.4 5063.0 5069.7 5111.6 5226.7 5228.0 1010.4 1016.0 1027.7 1038.3 1050.6 25318.5 25532.6 25762.8 26034.7 26078.6 1127.5 1127.6 1142.3 1152.6 1177.7 13055.2 13187.9 13382.8 13472.1 13524.0 288676.0 288831.0 288933.0 289575.0 289988.0 55599.8 55710.0 55716.7 55793.0 56390.7 1364.5 1450.9 1457.0 1461.3 1493.9 23609.6 24010.6 24563.3 24687.3 24717.4 39115.8 39754.6 39780.8 39852.2 39852.2 283706.0 283902.0 284342.0 284546.0 284631.0 26017.6 26134.9 32579.7 34596.0 34720.1 39142.8 40149.8 40256.0 40480.4 40555.8 30892.1 30952.4 31023.7 31183.5 31320.7 30029.5 30452.4 30894.7 31349.7 31483.2 35071.8 35150.0 35162.0 35776.0 36085.3 29667.6 29693.4 29744.0 30148.7 30169.0 23596.4 24844.5 24872.8 25092.9 25212.6 09:11:44 INFO - PID 3406 | 09:11:44 INFO - PID 3406 | [#2] jquery.html Cycles:180 Average:32085.93 Median:11584.35 stddev:91671.24 (791.3%) stddev-sans-first:91897.58 09:11:44 INFO - PID 3406 | Values: 430.3 430.9 431.2 436.7 445.3 19587.0 19619.7 20008.7 20226.5 20250.6 19779.4 20740.6 20813.0 21075.0 21361.7 5807.5 6014.2 6018.2 6019.2 6019.6 5786.5 5894.8 5949.8 5980.1 5986.4 5930.7 5945.8 5955.5 5956.6 5969.2 5880.1 5897.9 6038.9 6048.9 6072.4 5452.4 5502.6 5511.2 5512.8 5516.9 25151.1 25187.9 25365.4 25390.7 25473.6 1924.1 2013.2 2017.1 2023.5 2041.4 14880.3 15381.1 15571.8 16117.6 16202.6 1853.2 1863.5 1866.5 1867.2 1904.3 70640.0 84681.0 84877.6 85626.1 107248.5 11864.7 11916.1 11930.7 12164.3 12215.9 14965.0 15350.1 15851.9 16307.3 16409.2 7975.3 8092.7 8108.8 8301.7 8305.9 7607.9 7650.2 7759.8 7874.5 7923.1 7568.5 7820.9 7861.6 7928.2 7975.3 2059.2 2063.4 2093.7 2169.4 2319.8 987.3 993.9 995.3 1005.4 1026.1 20026.4 20222.7 20315.3 20402.7 20455.5 1019.9 1040.3 1044.1 1045.0 1058.7 10243.9 11190.9 11242.3 11279.3 11286.8 551420.0 553752.0 558794.0 559614.0 560072.0 73578.3 94795.0 99190.5 100002.5 104109.0 1170.2 1176.5 1204.5 1213.7 1216.0 16084.9 16853.2 17101.2 17405.2 17466.4 46886.3 47565.8 47859.0 47896.0 48969.0 62249.4 68906.0 70697.6 71271.6 71448.6 9131.5 9162.3 9180.6 9227.7 9300.1 14011.4 14629.8 14633.0 14728.8 14970.3 11802.1 11905.8 12149.4 12211.9 12271.1 10841.5 10972.2 11045.4 11048.0 11216.4 11377.0 11972.8 12212.1 12341.8 12497.9 8949.4 9077.1 9091.8 9356.8 9406.6 11632.2 11960.4 12052.1 12135.2 12205.9 09:11:44 INFO - PID 3406 | 09:11:44 INFO - PID 3406 | [#3] mootools.html Cycles:180 Average:6818.25 Median:2326.96 stddev:4396.68 (188.9%) stddev-sans-first:4389.81 09:11:44 INFO - PID 3406 | Values: 1350.3 1398.8 1408.8 1418.6 1424.5 10353.6 10748.6 10759.8 10762.0 10766.3 10469.7 10472.9 10668.3 10719.4 10739.6 2130.1 2145.3 2157.4 2161.8 2163.7 1991.6 2263.1 2329.4 2364.0 2404.9 2007.9 2231.1 2308.4 2321.2 2323.5 2216.4 2310.1 2318.2 2321.1 2324.5 2239.4 2257.6 2266.4 2276.6 2278.4 10999.6 11093.4 11095.7 11102.1 11102.1 6281.6 6460.2 6482.5 6496.7 6498.2 3797.3 3984.1 4174.6 4182.8 4220.9 1365.8 1415.7 1434.8 1465.4 1493.1 15663.6 15965.5 16087.8 16095.1 16490.1 5203.6 5315.4 5367.7 5401.3 5451.5 12373.6 12414.6 12417.3 12418.6 12427.2 6289.3 6294.9 6314.4 6316.2 6317.7 5684.8 5722.5 5745.4 5793.0 5925.9 4014.9 4071.6 4080.6 4088.1 4155.2 1029.2 1237.2 1241.2 1247.8 1260.6 694.2 800.9 829.4 832.6 844.0 5608.4 5931.6 5938.6 6048.4 6106.6 912.8 926.7 933.5 947.8 950.3 3833.4 3868.7 3874.9 3903.3 3913.9 14257.9 14815.6 14816.3 14820.0 14825.9 13679.5 13972.4 14671.0 14683.9 14689.0 10022.5 10036.1 10235.6 10296.8 10300.3 6190.5 6298.5 6444.4 6792.9 6857.9 12938.0 13007.8 13125.8 13264.7 13361.9 12569.7 12781.4 13099.9 13567.3 13655.3 8926.2 9043.9 9104.9 9115.7 9188.2 9908.5 10094.7 10100.4 10104.5 10122.6 8007.7 8032.9 8093.3 8119.0 8407.8 8119.0 8175.5 8194.4 8206.4 8406.9 7328.5 7406.4 7457.4 7473.5 7484.4 5310.0 5321.3 5340.2 5394.3 5543.5 5415.0 5421.1 5456.6 5484.0 5553.8 09:11:44 INFO - PID 3406 | 09:11:44 INFO - PID 3406 | [#4] prototype.html Cycles:180 Average:5266.24 Median:5943.84 stddev:1493.05 (25.1%) stddev-sans-first:1452.19 09:11:44 INFO - PID 3406 | Values: 416.5 419.8 425.8 427.6 431.5 5824.7 5958.1 5987.5 5995.2 6012.2 5776.7 5856.2 5861.8 5861.9 5876.4 4487.4 4489.7 4549.4 4622.9 4636.1 4623.0 4628.2 4634.2 4641.3 4642.3 4589.6 4602.6 4608.2 4611.9 4626.4 4495.6 4587.4 4596.4 4607.4 4618.3 4151.0 4151.4 4159.9 4164.6 4168.8 5701.7 5742.0 5751.2 5754.6 5768.2 5273.7 5454.2 5463.8 5478.7 5497.5 6246.5 6264.7 6267.3 6271.4 6273.7 4576.2 4725.8 4728.2 4729.2 4742.3 6170.5 6389.2 6537.9 6553.9 6623.7 4709.0 4822.4 4856.8 4865.7 4882.6 4887.5 4891.0 4915.5 4922.9 4925.0 5036.4 5115.7 5450.6 5512.6 5535.1 6214.8 6236.2 6240.0 6243.0 6302.3 1686.2 1700.1 1709.7 1714.3 1728.2 1177.4 1233.1 1235.1 1236.1 1237.1 5947.9 5951.9 5973.7 6022.0 6026.4 6278.0 6332.2 6332.6 6378.6 6399.6 5903.3 5905.1 5914.9 5960.5 5982.1 3325.2 3381.8 3382.9 3385.7 3392.8 6410.2 6572.3 6644.4 6659.6 6661.0 6482.6 6494.0 6501.7 6511.2 6536.4 6420.3 6511.7 6537.4 6583.3 6591.2 6301.6 6316.8 6317.6 6322.5 6373.9 6053.8 6263.3 6290.7 6297.1 6312.1 6282.4 6401.7 6408.4 6613.4 6652.8 4760.4 4769.8 4782.4 4804.8 4814.3 6165.5 6271.0 6333.1 6370.7 6379.8 6138.1 6156.7 6158.5 6159.6 6162.2 6138.3 6155.8 6156.8 6170.2 6178.7 5939.8 6131.6 6161.4 6177.7 6212.7 6111.3 6114.8 6143.7 6204.1 6213.0 5820.7 6010.0 6034.9 6040.3 6042.9 09:11:44 INFO - PID 3406 | 09:11:44 INFO - PID 3406 | [#5] yui.html Cycles:180 Average:17539.18 Median:4129.39 stddev:21687.85 (525.2%) stddev-sans-first:21712.09 09:11:44 INFO - PID 3406 | Values: 760.8 766.8 769.7 787.5 787.9 16176.3 16375.8 16394.3 16396.7 16429.3 13920.2 13977.2 13979.4 13987.1 13999.9 816.6 818.1 826.6 838.7 842.0 832.8 838.7 838.8 839.5 839.9 890.5 892.1 892.5 892.9 893.2 877.2 881.8 881.9 883.8 884.3 648.7 650.8 653.3 655.7 656.9 17135.0 17355.2 17357.6 17628.6 17886.6 16081.0 16308.8 16511.4 16524.8 16569.7 11751.6 12463.6 12579.8 12764.4 13294.1 3079.5 3090.8 3101.1 3175.5 3183.5 84839.0 84865.0 87204.6 87232.1 87579.6 12587.6 12620.3 12662.4 12889.7 12917.9 25317.6 25401.6 25495.9 25515.4 25531.4 7830.5 7912.4 7980.7 8043.4 8129.1 4093.9 4164.9 4180.5 4182.5 4182.9 4173.2 4182.5 4218.6 4237.6 4278.1 723.3 734.7 744.9 749.9 758.5 588.7 590.5 605.9 608.1 619.8 10551.7 10900.7 11140.9 11228.6 11241.8 622.2 629.6 632.8 635.4 637.2 4613.9 4879.5 4882.2 4899.1 4914.7 57528.0 59895.7 76892.0 77309.6 80569.2 58924.7 58931.0 62762.4 72046.0 74202.6 57356.7 57849.4 58066.0 58094.4 58118.4 11032.5 11494.7 11524.0 11559.8 11695.5 29745.0 30352.5 30656.2 31520.8 33265.0 52937.7 54402.7 54877.7 55578.4 56256.7 14112.7 14206.3 14469.9 14567.9 14656.6 15063.8 15534.1 15836.2 15999.0 16138.4 13756.5 13881.2 14239.6 14276.4 14815.1 14326.0 14715.2 15085.0 15599.6 15638.3 14171.7 14699.8 14980.9 15791.7 15850.1 10065.4 10380.7 10734.9 11006.7 11074.3 11192.3 11643.5 11688.6 11940.2 12062.5 09:11:44 INFO - PID 3406 | -------- Summary: end -------- 09:11:44 INFO - PID 3406 | 09:11:44 INFO - TEST-INFO | 3406: exit 0 09:11:44 INFO - TEST-OK | dromaeo_css | took 1121606ms 09:11:44 INFO - TEST-START | kraken 09:11:44 INFO - operating with platform_type : linux_ 09:11:44 INFO - Initialising browser for kraken test... 09:11:44 INFO - TEST-INFO | started process 3734 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2aTi0J/profile http://localhost:57104/getInfo.html) 09:11:48 INFO - PID 3734 | __metrics Screen width/height:1600/1200 09:11:48 INFO - PID 3734 | colorDepth:24 09:11:48 INFO - PID 3734 | Browser inner width/height: 1024/697 09:11:48 INFO - PID 3734 | __metrics 09:11:50 INFO - PID 3734 | 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" 09:11:50 INFO - PID 3734 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:11:51 INFO - TEST-INFO | 3734: exit 0 09:11:51 INFO - Browser initialized. 09:11:51 INFO - Running cycle 1/1 for kraken test... 09:11:51 INFO - TEST-INFO | started process 3829 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2aTi0J/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:12:03 INFO - PID 3829 | RSS: Main: 145682432 09:12:03 INFO - PID 3829 | 09:12:05 INFO - PID 3829 | Cycle 1(1): loaded http://localhost:57104/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57104/tests/kraken/driver.html?testName=audio-beat-detection) 09:12:06 INFO - PID 3829 | RSS: Main: 201265152 09:12:06 INFO - PID 3829 | 09:12:09 INFO - PID 3829 | Cycle 1(1): loaded http://localhost:57104/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57104/tests/kraken/driver.html?testName=audio-dft) 09:12:09 INFO - PID 3829 | RSS: Main: 180424704 09:12:09 INFO - PID 3829 | 09:12:12 INFO - PID 3829 | Cycle 1(1): loaded http://localhost:57104/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57104/tests/kraken/driver.html?testName=audio-fft) 09:12:13 INFO - PID 3829 | RSS: Main: 261582848 09:12:13 INFO - PID 3829 | 09:12:15 INFO - PID 3829 | Cycle 1(1): loaded http://localhost:57104/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57104/tests/kraken/driver.html?testName=audio-oscillator) 09:12:15 INFO - PID 3829 | RSS: Main: 173424640 09:12:15 INFO - PID 3829 | 09:12:18 INFO - PID 3829 | Cycle 1(1): loaded http://localhost:57104/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57104/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:12:18 INFO - PID 3829 | RSS: Main: 176869376 09:12:18 INFO - PID 3829 | 09:12:21 INFO - PID 3829 | Cycle 1(1): loaded http://localhost:57104/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57104/tests/kraken/driver.html?testName=imaging-darkroom) 09:12:22 INFO - PID 3829 | RSS: Main: 216014848 09:12:22 INFO - PID 3829 | 09:12:25 INFO - PID 3829 | Cycle 1(1): loaded http://localhost:57104/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57104/tests/kraken/driver.html?testName=imaging-desaturate) 09:12:26 INFO - PID 3829 | RSS: Main: 224735232 09:12:26 INFO - PID 3829 | 09:12:29 INFO - PID 3829 | Cycle 1(1): loaded http://localhost:57104/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57104/tests/kraken/driver.html?testName=json-parse-financial) 09:12:29 INFO - PID 3829 | RSS: Main: 230150144 09:12:29 INFO - PID 3829 | 09:12:31 INFO - PID 3829 | Cycle 1(1): loaded http://localhost:57104/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57104/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:12:31 INFO - PID 3829 | RSS: Main: 178573312 09:12:31 INFO - PID 3829 | 09:12:33 INFO - PID 3829 | Cycle 1(1): loaded http://localhost:57104/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57104/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:12:34 INFO - PID 3829 | RSS: Main: 186085376 09:12:34 INFO - PID 3829 | 09:12:36 INFO - PID 3829 | Cycle 1(1): loaded http://localhost:57104/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57104/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:12:36 INFO - PID 3829 | RSS: Main: 194584576 09:12:36 INFO - PID 3829 | 09:12:39 INFO - PID 3829 | Cycle 1(1): loaded http://localhost:57104/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57104/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:12:40 INFO - PID 3829 | RSS: Main: 204025856 09:12:40 INFO - PID 3829 | 09:12:43 INFO - PID 3829 | Cycle 1(1): loaded http://localhost:57104/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57104/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:12:43 INFO - PID 3829 | RSS: Main: 200151040 09:12:43 INFO - PID 3829 | 09:12:45 INFO - PID 3829 | Cycle 1(1): loaded http://localhost:57104/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57104/tests/kraken/driver.html?testName=ai-astar) 09:12:45 INFO - PID 3829 | RSS: Main: 268845056 09:12:45 INFO - PID 3829 | 09:12:45 INFO - PID 3829 | __start_tp_report 09:12:45 INFO - PID 3829 | _x_x_mozilla_page_load 09:12:45 INFO - PID 3829 | _x_x_mozilla_page_load_details 09:12:45 INFO - PID 3829 | |i|pagename|runs| 09:12:45 INFO - PID 3829 | |0;ai-astar;101;110;110;109;111;110;109;109;110;110 09:12:45 INFO - PID 3829 | |1;audio-beat-detection;151;117;118;161;124;117;119;116;147;118 09:12:45 INFO - PID 3829 | |2;audio-dft;162;160;161;161;161;162;163;162;161;160 09:12:45 INFO - PID 3829 | |3;audio-fft;73;74;74;101;75;75;74;74;96;74 09:12:45 INFO - PID 3829 | |4;audio-oscillator;82;82;109;83;82;82;96;82;82;98 09:12:45 INFO - PID 3829 | |5;imaging-gaussian-blur;113;113;113;113;113;112;113;113;112;112 09:12:45 INFO - PID 3829 | |6;imaging-darkroom;147;147;146;146;147;147;146;147;147;146 09:12:45 INFO - PID 3829 | |7;imaging-desaturate;94;94;94;94;94;94;94;94;94;94 09:12:45 INFO - PID 3829 | |8;json-parse-financial;56;57;55;56;57;57;58;58;57;59 09:12:45 INFO - PID 3829 | |9;json-stringify-tinderbox;55;56;56;55;55;56;55;55;56;56 09:12:45 INFO - PID 3829 | |10;stanford-crypto-aes;79;79;78;79;78;79;79;79;78;86 09:12:45 INFO - PID 3829 | |11;stanford-crypto-ccm;136;135;126;133;135;126;134;128;163;132 09:12:45 INFO - PID 3829 | |12;stanford-crypto-pbkdf2;175;181;182;174;179;179;183;183;181;180 09:12:45 INFO - PID 3829 | |13;stanford-crypto-sha256-iterative;65;64;65;66;64;67;65;64;64;65 09:12:45 INFO - PID 3829 | __end_tp_report 09:12:45 INFO - PID 3829 | __start_cc_report 09:12:45 INFO - PID 3829 | _x_x_mozilla_cycle_collect,1741 09:12:45 INFO - PID 3829 | __end_cc_report 09:12:45 INFO - PID 3829 | __startTimestamp1490285565605__endTimestamp 09:12:45 INFO - PID 3829 | 09:12:45 INFO - PID 3829 | ------- Summary: start ------- 09:12:45 INFO - PID 3829 | Number of tests: 14 09:12:45 INFO - PID 3829 | 09:12:45 INFO - PID 3829 | [#0] ai-astar Cycles:10 Average:108.90 Median:110.00 stddev:2.85 (2.6%) stddev-sans-first:0.67 09:12:45 INFO - PID 3829 | Values: 101.0 110.0 110.0 109.0 111.0 110.0 109.0 109.0 110.0 110.0 09:12:45 INFO - PID 3829 | 09:12:45 INFO - PID 3829 | [#1] audio-beat-detection Cycles:10 Average:128.80 Median:121.50 stddev:17.18 (14.1%) stddev-sans-first:16.23 09:12:45 INFO - PID 3829 | Values: 151.0 117.0 118.0 161.0 124.0 117.0 119.0 116.0 147.0 118.0 09:12:45 INFO - PID 3829 | 09:12:45 INFO - PID 3829 | [#2] audio-dft Cycles:10 Average:161.30 Median:161.50 stddev:0.95 (0.6%) stddev-sans-first:0.97 09:12:45 INFO - PID 3829 | Values: 162.0 160.0 161.0 161.0 161.0 162.0 163.0 162.0 161.0 160.0 09:12:45 INFO - PID 3829 | 09:12:45 INFO - PID 3829 | [#3] audio-fft Cycles:10 Average:79.00 Median:74.00 stddev:10.36 (14.0%) stddev-sans-first:10.76 09:12:45 INFO - PID 3829 | Values: 73.0 74.0 74.0 101.0 75.0 75.0 74.0 74.0 96.0 74.0 09:12:45 INFO - PID 3829 | 09:12:45 INFO - PID 3829 | [#4] audio-oscillator Cycles:10 Average:87.80 Median:82.00 stddev:9.69 (11.8%) stddev-sans-first:10.05 09:12:45 INFO - PID 3829 | Values: 82.0 82.0 109.0 83.0 82.0 82.0 96.0 82.0 82.0 98.0 09:12:45 INFO - PID 3829 | 09:12:45 INFO - PID 3829 | [#5] imaging-gaussian-blur Cycles:10 Average:112.70 Median:113.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 09:12:45 INFO - PID 3829 | Values: 113.0 113.0 113.0 113.0 113.0 112.0 113.0 113.0 112.0 112.0 09:12:45 INFO - PID 3829 | 09:12:45 INFO - PID 3829 | [#6] imaging-darkroom Cycles:10 Average:146.60 Median:147.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 09:12:45 INFO - PID 3829 | Values: 147.0 147.0 146.0 146.0 147.0 147.0 146.0 147.0 147.0 146.0 09:12:45 INFO - PID 3829 | 09:12:45 INFO - PID 3829 | [#7] imaging-desaturate Cycles:10 Average:94.00 Median:94.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 09:12:45 INFO - PID 3829 | Values: 94.0 94.0 94.0 94.0 94.0 94.0 94.0 94.0 94.0 94.0 09:12:45 INFO - PID 3829 | 09:12:45 INFO - PID 3829 | [#8] json-parse-financial Cycles:10 Average:57.00 Median:57.00 stddev:1.15 (2.0%) stddev-sans-first:1.17 09:12:45 INFO - PID 3829 | Values: 56.0 57.0 55.0 56.0 57.0 57.0 58.0 58.0 57.0 59.0 09:12:45 INFO - PID 3829 | 09:12:45 INFO - PID 3829 | [#9] json-stringify-tinderbox Cycles:10 Average:55.50 Median:56.00 stddev:0.53 (0.9%) stddev-sans-first:0.53 09:12:45 INFO - PID 3829 | Values: 55.0 56.0 56.0 55.0 55.0 56.0 55.0 55.0 56.0 56.0 09:12:45 INFO - PID 3829 | 09:12:45 INFO - PID 3829 | [#10] stanford-crypto-aes Cycles:10 Average:79.40 Median:79.00 stddev:2.37 (3.0%) stddev-sans-first:2.51 09:12:45 INFO - PID 3829 | Values: 79.0 79.0 78.0 79.0 78.0 79.0 79.0 79.0 78.0 86.0 09:12:45 INFO - PID 3829 | 09:12:45 INFO - PID 3829 | [#11] stanford-crypto-ccm Cycles:10 Average:134.80 Median:134.50 stddev:10.59 (7.9%) stddev-sans-first:11.22 09:12:45 INFO - PID 3829 | Values: 136.0 135.0 126.0 133.0 135.0 126.0 134.0 128.0 163.0 132.0 09:12:45 INFO - PID 3829 | 09:12:45 INFO - PID 3829 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.70 Median:181.00 stddev:3.09 (1.7%) stddev-sans-first:2.77 09:12:45 INFO - PID 3829 | Values: 175.0 181.0 182.0 174.0 179.0 179.0 183.0 183.0 181.0 180.0 09:12:45 INFO - PID 3829 | 09:12:45 INFO - PID 3829 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.90 Median:65.00 stddev:0.99 (1.5%) stddev-sans-first:1.05 09:12:45 INFO - PID 3829 | Values: 65.0 64.0 65.0 66.0 64.0 67.0 65.0 64.0 64.0 65.0 09:12:45 INFO - PID 3829 | -------- Summary: end -------- 09:12:45 INFO - PID 3829 | 09:12:46 INFO - TEST-INFO | 3829: exit 0 09:12:46 INFO - TEST-OK | kraken | took 61916ms 09:12:46 INFO - SUITE-END | took 1183s 09:12:46 INFO - Completed test suite (00:19:43) 09:12:46 INFO - javascript benchmark 09:12:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7477.873286309495, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3319.9376199616117, 3331.7334613230946, 3350.514175444562, 3355.8489867955946, 3376.6960973512378, 5562.9443705562935, 5655.20827084208, 5710.486056254937, 5734.0, 5741.684207368593, 5714.542836573075, 5748.310202775673, 5756.0, 5799.565032622549, 5837.270341207349, 4949.529794669501, 5004.12427825131, 5028.4971502849785, 5110.591152707793, 5125.256837758523, 5118.9744051279695, 5152.098382783017, 5153.149730294497, 5168.689878607295, 5199.974000129994, 4999.500049995007, 5030.798768049311, 5031.798728050873, 5077.162268225738, 5079.517445842639, 5028.1200789862, 5072.315237442935, 5081.085404627165, 5126.769295381717, 5138.7687554059785, 4968.080905032581, 5014.448410674823, 5025.0954828130925, 5043.52086551777, 5073.695578265316, 5560.471755183251, 5614.859628509279, 5715.199872017921, 5715.771369145229, 5722.799702010451, 6105.267367915834, 6139.324674285825, 6159.476444502223, 6172.814815555495, 6182.567220294582, 6200.751969921198, 6217.595856269328, 6225.688715564203, 6233.096201050867, 6233.252009758812, 5714.2571465710025, 5751.654900705971, 5754.884902301972, 5761.452661997104, 5811.331696854873, 6199.194104766351, 6256.968715156395, 6285.465735412541, 6297.811065668037, 6337.081123237105, 5930.377310382348, 5985.132155837379, 5997.490213331916, 6000.0, 6137.0794380842335, 6134.228053882901, 6142.621558143092, 6147.569670123048, 6153.815385538441, 6166.167567378437, 6065.090236464477, 6141.631502109798, 6142.815715528542, 6161.84595385119, 6166.660833654102, 6048.153258543807, 6103.572749907464, 6105.436769732072, 6123.724432400552, 6129.908051379233, 4674.766261686902, 4696.835610753607, 4710.4347478302825, 4716.0, 4719.0, 4192.413062171298, 4193.454850869368, 4198.1393814268, 4206.390073439396, 4228.682848786353, 5752.309722833287, 5813.418658134237, 5834.183214349994, 5858.8535286617325, 5861.443162899518, 6145.740123274718, 6165.84585385369, 6178.227721534809, 6181.134641150243, 6197.597156184924, 5792.855178620568, 5794.710264486759, 5799.88400231993, 5803.506701930299, 5810.88378232433, 5168.198929165986, 5183.352080989876, 5235.031519168966, 5238.266642670029, 5239.266502689627, 6394.360563943568, 6401.647909364937, 6476.967615161894, 6481.578697384655, 6483.578567393104, 6176.629402235881, 6179.443850053517, 6182.227221597301, 6185.474234690102, 6191.25704915413, 6472.190976127984, 6476.190476190476, 6476.5466417350335, 6484.383983521558, 6490.058941453557, 5937.020391635505, 6026.789062382795, 6055.848603784764, 6064.211652485149, 6067.635941843503, 6101.762223876893, 6122.886104953835, 6136.733532366248, 6143.972489078683, 6171.907421388593, 5994.340622531431, 6020.458158765732, 6020.578559500968, 6063.666498342457, 6081.908771368345, 5994.67029313392, 6022.277326720821, 6033.698315084316, 6046.969765151146, 6080.90878636812, 6027.186329845415, 6038.4867286279405, 6045.909311360421, 6051.243594550681, 6051.576389652858, 5956.731947062306, 5967.075103358986, 5969.432903874125, 6029.246344206974, 6029.758809647565, 5824.621399609012, 5832.3292821326095, 5851.771128063155, 5859.619124756877, 5889.0], "value": 5643.6655465429185, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [603.5262319079524, 657.2343220148528, 674.8886433738431, 1121.3496172220114, 1124.3029321820468, 29832.1050368489, 37675.0, 38590.61409385909, 38624.80687596565, 38685.613143868555, 39660.0, 40136.19727605446, 40153.598464015355, 40263.79868100663, 40354.14864844596, 6042.57701960863, 6174.44430001302, 7968.083670377909, 8137.9593102034405, 8145.104038555754, 7877.921220787776, 8051.114377418479, 8065.2741253287195, 8065.637046332899, 8067.878981815277, 8024.478408903403, 8300.045494768088, 8318.83362332756, 8321.83356332873, 8322.583870806466, 8194.426390152692, 8254.339652827759, 8269.83460330796, 8270.710525131592, 8286.088530261666, 8089.352851771844, 8144.389170812154, 8161.5919204039565, 8162.347012239066, 8165.550894700789, 40258.597414025775, 40482.595174048176, 40491.59508404908, 40522.39216411756, 40581.59418405837, 2973.44991176633, 2995.056557184502, 3002.4145291668133, 3004.2339203503184, 3028.0915725282325, 28028.579571306447, 28100.297492562848, 28276.57585136204, 28445.85777071122, 29277.56083658747, 3486.093615659947, 3510.227749895019, 3542.6457354264367, 3548.6628770266784, 3596.1009747563107, 217752.0, 218149.0, 218386.0, 220132.0, 220191.0, 50399.0, 51112.74443627758, 51130.74434627803, 51136.74431627818, 51189.48810511847, 53736.46263537393, 53878.0, 54017.459825401245, 54042.0, 54153.1877021845, 14005.719885602435, 14210.0, 14266.714665706628, 14268.0, 14279.714405711828, 9787.0, 9923.354981926152, 10671.0, 11364.318140911571, 11418.086553075735, 13367.732645347038, 13439.731205375838, 13561.050726449053, 13569.796453053015, 13689.383977721027, 5062.974685126551, 5069.746512674352, 5111.5655169310285, 5226.738663066832, 5227.973860130675, 1010.3584224017654, 1015.9898401016043, 1027.6968294353185, 1038.2628333882876, 1050.558765318568, 25318.493630127294, 25532.617010744852, 25762.812872467523, 26034.73965260361, 26078.608820867703, 1127.548980407827, 1127.6335191062938, 1142.2974870454777, 1152.6484422251126, 1177.7055736065984, 13055.151415157985, 13187.868121318665, 13382.799258011137, 13472.12431191989, 13524.0, 288676.0, 288831.0, 288933.0, 289575.0, 289988.0, 55599.77600895919, 55710.0, 55716.72141639266, 55793.0, 56390.71804640951, 1364.515596963064, 1450.9056911300731, 1457.0310743355762, 1461.3423959218394, 1493.8804895608325, 23609.645855312527, 24010.63984040206, 24563.263102106965, 24687.259382218563, 24717.38206544779, 39115.804420977714, 39754.614723116705, 39780.801095994335, 39852.20295594014, 39852.2029559413, 283706.0, 283902.0, 284342.0, 284546.0, 284631.0, 26017.6097358536, 26134.86932565325, 32579.69681212725, 34596.0, 34720.131996700286, 39142.80428597839, 40149.799251003555, 40256.0, 40480.3927941087, 40555.79722101371, 30892.073237802902, 30952.380952380376, 31023.689763102982, 31183.53224701586, 31320.686793131776, 30029.54955675623, 30452.390952181275, 30894.69105308918, 31349.686503134675, 31483.212919677193, 35071.824640876635, 35150.0, 35162.0, 35776.0, 36085.27829443449, 29667.554986674786, 29693.40613187681, 29744.0, 30148.698513015468, 30169.0, 23596.410089747893, 24844.503109937337, 24872.751272487767, 25092.874535627205, 25212.621810673223], "value": 17108.892956912747, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [430.25565771215776, 430.92286871509737, 431.1656943813721, 436.67904090493494, 445.26753490508116, 19587.0, 19619.705704414428, 20008.69986950197, 20226.494337641565, 20250.59498810025, 19779.406617801455, 20740.585188296223, 20813.0, 21075.0, 21361.67957480639, 5807.477327040566, 6014.248218972629, 6018.15745795589, 6019.247594050744, 6019.578629495915, 5786.479216870481, 5894.793682221123, 5949.8215053548465, 5980.073088671263, 5986.431289027535, 5930.673812940287, 5945.762169513214, 5955.464008239258, 5956.553258505608, 5969.22400087988, 5880.147378630118, 5897.941020589782, 6038.939610603926, 6048.879022419571, 6072.392760723892, 5452.372977107644, 5502.5597952164135, 5511.200875872976, 5512.834614961557, 5516.862078448071, 25151.119710810217, 25187.87406062958, 25365.365865853502, 25390.746092539022, 25473.61789573158, 1924.134139637155, 2013.2047841102828, 2017.0923084611836, 2023.494126468383, 2041.4181958142008, 14880.330385132695, 15381.077135371914, 15571.844281557265, 16117.597060073474, 16202.594935126599, 1853.1753369750465, 1863.5341164708823, 1866.4773863318292, 1867.1691097461635, 1904.266857259951, 70640.0, 84681.0, 84877.57561212216, 85626.14373856244, 107248.46375768149, 11864.703382415508, 11916.106292028133, 11930.701732456584, 12164.289891582976, 12215.938920305342, 14965.0, 15350.07899526032, 15851.920740396225, 16307.347706091861, 16409.179541022902, 7975.282224599814, 8092.71675491355, 8108.756737297773, 8301.6679332826, 8305.875411868827, 7607.923920760722, 7650.19672934345, 7759.844803104019, 7874.527528348317, 7923.12845586985, 7568.508046976929, 7820.871651339509, 7861.567613781182, 7928.246816552418, 7975.282224599814, 2059.217497351027, 2063.4119276006268, 2093.6964140199584, 2169.43594665385, 2319.840585313653, 987.3236832769576, 993.8509223616371, 995.3132338686428, 1005.4168582222294, 1026.0867827633413, 20026.399208023784, 20222.696659550118, 20315.288964886455, 20402.69395959062, 20455.488612784502, 1019.9490025498695, 1040.328987802863, 1044.1125043712784, 1044.9738756531146, 1058.6771034834296, 10243.865911189758, 11190.888091119148, 11242.325460472235, 11279.32324060559, 11286.774264514665, 551420.0, 553752.0, 558794.0, 559614.0, 560072.0, 73578.26421735821, 94795.0, 99190.52833150805, 100002.4999874996, 104109.0, 1170.1628443344039, 1176.5470293936896, 1204.5121725701106, 1213.666241783499, 1215.9878401215874, 16084.919575402047, 16853.24160412809, 17101.23044463027, 17405.21516557345, 17466.388676396266, 46886.30432938615, 47565.76217118892, 47859.0, 47896.0, 48969.0, 62249.37750622436, 68906.0, 70697.64651176712, 71271.64364178145, 71448.64135189986, 9131.452112873249, 9162.312826537904, 9180.586873590571, 9227.723168304961, 9300.11648893354, 14011.439542418597, 14629.780553291923, 14633.0, 14728.77906831377, 14970.251487425803, 11802.114841386758, 11905.761884762429, 12149.448615790783, 12211.938940305241, 12271.141020128505, 10841.512131953923, 10972.23194376418, 11045.39250341239, 11048.0, 11216.383098929637, 11377.0, 11972.820407693718, 12212.08409369283, 12341.814872776737, 12497.93751031239, 8949.418287811272, 9077.137671921157, 9091.772705682411, 9356.812863742824, 9406.576704048472, 11632.18574699763, 11960.401979901144, 12052.09609279325, 12135.211211271238, 12205.877941220475], "value": 9874.122846836157, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1350.3045931345357, 1398.790181472779, 1408.8027676125341, 1418.6098822823728, 1424.5085445521293, 10353.585856565738, 10748.623798167046, 10759.78480430392, 10762.0, 10766.300190487613, 10469.738256543591, 10472.947635261831, 10668.253222274425, 10719.410432426213, 10739.570417183322, 2130.1160018592263, 2145.313499680103, 2157.385145233609, 2161.8270538356974, 2163.740351157863, 1991.5618563915916, 2263.1187513414493, 2329.4176455886027, 2364.0189321431585, 2404.9398765030837, 2007.8694884832512, 2231.0852550454315, 2308.3536609749212, 2321.164380822911, 2323.546908352872, 2216.4015715756655, 2310.1336998625516, 2318.188633978111, 2321.0599707118645, 2324.500232450023, 2239.4177513846516, 2257.604919139144, 2266.388075219698, 2276.64711969645, 2278.441781763462, 10999.560017599286, 11093.3898635575, 11095.72260693489, 11102.056325212367, 11102.111831053497, 6281.59169653971, 6460.160179176724, 6482.546221764478, 6496.707648155844, 6498.1552398188405, 3797.2974999625158, 3984.063745019918, 4174.624283814472, 4182.790860456965, 4220.9155816883795, 1365.7669710024181, 1415.716856628668, 1434.7560914644534, 1465.4065103633045, 1493.126520985225, 15663.608409789733, 15965.521034368987, 16087.839121608868, 16095.114768687718, 16490.093044882527, 5203.609729270244, 5315.362156541162, 5367.677939323653, 5401.270828438111, 5451.454854514517, 12373.62879113628, 12414.62756117318, 12417.31704756247, 12418.627441176599, 12427.192232504858, 6289.308176100625, 6294.937050629435, 6314.400131987454, 6316.178896743486, 6317.747290108436, 5684.8294551163535, 5722.484976352148, 5745.425457454221, 5793.04414771558, 5925.851853703605, 4014.8568249149835, 4071.592840715964, 4080.6327430531396, 4088.0801819590947, 4155.231282212801, 1029.1663752360462, 1237.189640785287, 1241.2304371289677, 1247.7754004279136, 1260.6281147061638, 694.2259380790382, 800.931920786728, 829.3738227638095, 832.633641197871, 844.0335815491228, 5608.355039170548, 5931.555133364928, 5938.584299099108, 6048.425399587032, 6106.603070800384, 912.808310254854, 926.7127190570944, 933.5285680731323, 947.7725345917069, 950.296780382512, 3833.4058220975794, 3868.6905047596124, 3874.903127421837, 3903.316919539069, 3913.9021524461546, 14257.92871035659, 14815.555533334018, 14816.3332650031, 14820.0, 14825.92587037058, 13679.521216757366, 13972.441102355995, 14671.0, 14683.92658036703, 14689.0, 10022.548985295532, 10036.096751292418, 10235.641752538626, 10296.794064118676, 10300.330478518872, 6190.535709821691, 6298.496120310456, 6444.387783160592, 6792.87917493608, 6857.931420685729, 12938.0, 13007.804882926954, 13125.803112953123, 13264.734705306033, 13361.933190333986, 12569.685757856127, 12781.424835882593, 13099.934500327437, 13567.321633918067, 13655.317234138054, 8926.152015558511, 9043.864342034743, 9104.863427048467, 9115.680951166678, 9188.219001384958, 9908.455034973145, 10094.697159085239, 10100.393976361442, 10104.545295461576, 10122.645707400206, 8007.719729809429, 8032.919670803451, 8093.312068474245, 8118.959405202936, 8407.789805254917, 8118.959405202936, 8175.468594541336, 8194.426390152632, 8206.384521161057, 8406.873896891673, 7328.450366222448, 7406.44451666138, 7457.40340772726, 7473.476856619985, 7484.401247900264, 5310.044192045314, 5321.3348331458565, 5340.172273297645, 5394.271773310553, 5543.52880005204, 5414.972925135349, 5421.1055175895535, 5456.563474922076, 5484.040292948798, 5553.8333849984565], "value": 5142.058524622292, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [416.5418040155828, 419.8446574767336, 425.823283337415, 427.62796367160576, 431.53807528052465, 5824.737886795094, 5958.136070269814, 5987.520998320135, 5995.190649262348, 6012.218411606491, 5776.653400795955, 5856.243127061875, 5861.824145275638, 5861.8569378971115, 5876.4417380348905, 4487.439070116236, 4489.663275254353, 4549.431321084864, 4622.930656040162, 4636.142313671965, 4622.9768851155695, 4628.1669299526075, 4634.189016922042, 4641.280601506772, 4642.259152817694, 4589.632829373659, 4602.631789456836, 4608.216603177451, 4611.861644150681, 4626.398568186131, 4495.550444955495, 4587.3577699121815, 4596.4254468191475, 4607.447106347243, 4618.2610782275015, 4150.958490415087, 4151.4395556599775, 4159.9376009359885, 4164.583541645841, 4168.7707176105305, 5701.657900525982, 5741.971290143522, 5751.166080918243, 5754.568407369422, 5768.221290125821, 5273.6572122811895, 5454.154606036071, 5463.836084917459, 5478.671279723229, 5497.45025497451, 6246.500279977591, 6264.718087686065, 6267.310595834455, 6271.400792797853, 6273.717682704244, 4576.199165146119, 4725.763711814396, 4728.172569800306, 4729.219678753002, 4742.264948932921, 6170.457385653586, 6389.201349831271, 6537.93462065378, 6553.901691474632, 6623.714818387649, 4708.95291047092, 4822.445418776885, 4856.8300109495995, 4865.683730557503, 4882.5605695487575, 4887.511248875155, 4890.997345544155, 4915.48387419323, 4922.8769280768265, 4925.0, 5036.395632524121, 5115.7442127893455, 5450.591205659592, 5512.5589952803675, 5535.086710692771, 6214.782482613087, 6236.18929539157, 6240.0, 6242.968785156136, 6302.275238347557, 1686.215909602041, 1700.115939711368, 1709.734991076385, 1714.3314107498086, 1728.2222999650207, 1177.4466000979526, 1233.0751936047964, 1235.1045492018216, 1236.1223531292703, 1237.0783766094137, 5947.851303717355, 5951.880962380728, 5973.671448070398, 6021.969890150609, 6026.397360264026, 6278.0, 6332.208473940757, 6332.602940739073, 6378.617282962944, 6399.55203135776, 5903.26209223847, 5905.143754155623, 5914.9112763308585, 5960.523158147338, 5982.1325907744, 3325.235195904907, 3381.847816848248, 3382.915427114342, 3385.712214461749, 3392.8133952632347, 6410.1987251593555, 6572.34276572339, 6644.368784965421, 6659.633720145439, 6661.0, 6482.611043337415, 6494.0, 6501.739930402731, 6511.153550038464, 6536.411722944863, 6420.261669907927, 6511.73953041873, 6537.378948999837, 6583.275839657634, 6591.242007169237, 6301.590396624205, 6316.842078948063, 6317.557770955989, 6322.525810564134, 6373.8725225496155, 6053.757849685963, 6263.27972283175, 6290.654014029273, 6297.103332466933, 6312.116303717575, 6282.403171698681, 6401.743930242738, 6408.423241908251, 6613.437857782143, 6652.767153149616, 4760.428748550196, 4769.8313913091515, 4782.378290822171, 4804.783784729765, 4814.277858321279, 6165.5375846810775, 6270.968645156745, 6333.081703153109, 6370.713317900798, 6379.776707815204, 6138.140660307471, 6156.692165391623, 6158.507319414346, 6159.5688301818445, 6162.168107305636, 6138.263408391022, 6155.753769849156, 6156.846078848064, 6170.167027451236, 6178.66017369049, 5939.821805345847, 6131.601445906002, 6161.383861613803, 6177.722002509988, 6212.689365531795, 6111.297200821962, 6114.785982490592, 6143.692815359124, 6204.13142160089, 6213.0, 5820.650760954356, 6009.969950150221, 6034.9094763577705, 6040.2751669798145, 6042.90935635957], "value": 4815.953761533553, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [760.7603604864468, 766.8044648614605, 769.6805825582384, 787.5156778581174, 787.9290863822255, 16176.27206775695, 16375.83624163758, 16394.344226230936, 16396.67206655866, 16429.34282628695, 13920.234387108705, 13977.23125228112, 13979.370928308224, 13987.090839095452, 13999.860001399982, 816.5508970066469, 818.1450384348341, 826.5949684654536, 838.714836955435, 841.9663213471484, 832.81261716114, 838.6896848166188, 838.7986883148031, 839.5088873009297, 839.9034111077212, 890.5191196753746, 892.0588778838323, 892.5091199840094, 892.9330300227476, 893.1916615462984, 877.1886005444937, 881.8060026794127, 881.9250363719092, 883.7746374674481, 884.2925659472396, 648.6853875870199, 650.8340373204802, 653.2846533046306, 655.6787174284614, 656.8916128838783, 17135.0, 17355.21901514435, 17357.566060848454, 17628.647427051514, 17886.642267154715, 16081.0, 16308.836911630851, 16511.422100226555, 16524.75212871808, 16569.6686066278, 11751.588694395741, 12463.563775267912, 12579.811302830465, 12764.361781910868, 13294.135881167693, 3079.491883839164, 3090.8300043497597, 3101.147184524274, 3175.523671449278, 3183.5383869338816, 84839.0, 84865.0, 87204.56397717971, 87232.12767872367, 87579.56210218908, 12587.622371328875, 12620.30588317633, 12662.430190641444, 12889.677758056123, 12917.935410323076, 25317.62023569648, 25401.61897571538, 25495.872520637276, 25515.448455154336, 25531.36171595725, 7830.451868369273, 7912.367010639135, 7980.720674776355, 8043.436959412785, 8129.065157506844, 4093.8976525586504, 4164.875053748392, 4180.498340199135, 4182.53992060873, 4182.895427614334, 4173.248815213291, 4182.477190351206, 4218.557051509547, 4237.555056719, 4278.122984788111, 723.3345322303494, 734.7061175529831, 744.9329560339595, 749.9287567681062, 758.4577027425418, 588.7497813429276, 590.4892268188072, 605.9272887253558, 608.0875646093012, 619.8419403052283, 10551.736206594896, 10900.672979810619, 11140.871976736673, 11228.606998755004, 11241.775164496665, 622.2004723929729, 629.6411045703904, 632.8449529865212, 635.3646353646353, 637.1875858280729, 4613.861584152481, 4879.463259041502, 4882.2432522959, 4899.1426500362295, 4914.69276943383, 57528.0, 59895.70052149799, 76892.0, 77309.61345193238, 80569.19430805734, 58924.70537647284, 58931.0, 62762.372376275656, 72046.0, 74202.6289868558, 57356.71321643365, 57849.42150578524, 58066.0, 58094.4190558089, 58118.41881581215, 11032.503537340679, 11494.655160345203, 11524.0, 11559.826602600799, 11695.532178712756, 29745.0, 30352.544711828898, 30656.233594160323, 31520.842395787873, 33265.0, 52937.73531132474, 54402.727986359816, 54877.72561137169, 55578.44421555894, 56256.718716406154, 14112.717745645235, 14206.289685515889, 14469.85530144685, 14567.92716036413, 14656.633584160054, 15063.849361506684, 15534.1456219909, 15836.208189590705, 15999.0, 16138.435154769526, 13756.518521851687, 13881.236531990837, 14239.64400889986, 14276.357563909442, 14815.111093334435, 14326.0, 14715.190664513126, 15085.0, 15599.610009749847, 15638.29627666735, 14171.716565668836, 14699.779503307673, 14980.925095374454, 15791.68416631684, 15850.128242946748, 10065.446400447752, 10380.740481488023, 10734.946325268324, 11006.724831879266, 11074.335539867634, 11192.328460292083, 11643.53425862956, 11688.590899318484, 11940.223885447418, 12062.51749929993], "value": 7405.194149430652, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1490.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 110.0, 110.0, 109.0, 111.0, 110.0, 109.0, 109.0, 110.0, 110.0], "value": 108.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 117.0, 118.0, 161.0, 124.0, 117.0, 119.0, 116.0, 147.0, 118.0], "value": 128.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 160.0, 161.0, 161.0, 161.0, 162.0, 163.0, 162.0, 161.0, 160.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 74.0, 101.0, 75.0, 75.0, 74.0, 74.0, 96.0, 74.0], "value": 79.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 109.0, 83.0, 82.0, 82.0, 96.0, 82.0, 82.0, 98.0], "value": 87.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 113.0, 113.0, 113.0, 112.0, 113.0, 113.0, 112.0, 112.0], "value": 112.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 146.0, 146.0, 147.0, 147.0, 146.0, 147.0, 147.0, 146.0], "value": 146.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0], "value": 94.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 57.0, 55.0, 56.0, 57.0, 57.0, 58.0, 58.0, 57.0, 59.0], "value": 57.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 56.0, 55.0, 55.0, 56.0, 55.0, 55.0, 56.0, 56.0], "value": 55.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 78.0, 79.0, 78.0, 79.0, 79.0, 79.0, 78.0, 86.0], "value": 79.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 135.0, 126.0, 133.0, 135.0, 126.0, 134.0, 128.0, 163.0, 132.0], "value": 134.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 181.0, 182.0, 174.0, 179.0, 179.0, 183.0, 183.0, 181.0, 180.0], "value": 179.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 65.0, 66.0, 64.0, 67.0, 65.0, 64.0, 64.0, 65.0], "value": 64.9, "unit": "score"}]}]} 09:12:46 INFO - Return code: 0 09:12:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:12:46 ERROR - # TBPL SUCCESS # 09:12:46 INFO - Running post-action listener: _resource_record_post_action 09:12:46 INFO - [mozharness: 2017-03-23 16:12:46.946017Z] Finished run-tests step (success) 09:12:46 INFO - Running post-run listener: _resource_record_post_run 09:12:47 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 7458816; Write bytes: 369930240; Read time: 1464; Write time: 439656 09:12:47 INFO - TinderboxPrint: CPU usage
12.9% 09:12:47 INFO - TinderboxPrint: I/O read bytes / time
7,458,816 / 1,464 09:12:47 INFO - TinderboxPrint: I/O write bytes / time
369,930,240 / 439,656 09:12:47 INFO - TinderboxPrint: CPU idle
8,343.9 (87.0%) 09:12:47 INFO - TinderboxPrint: CPU user
1,188.5 (12.4%) 09:12:47 INFO - TinderboxPrint: Swap in / out
0 / 0 09:12:47 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 4096; Write bytes: 112168960; Read time: 320; Write time: 165508 09:12:47 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 5238784; Write bytes: 257536000; Read time: 944; Write time: 274056 09:12:47 INFO - Running post-run listener: _upload_blobber_files 09:12:47 INFO - Blob upload gear active. 09:12:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:12:47 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 09:12:47 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'] 09:12:47 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 09:12:47 INFO - (blobuploader) - INFO - Open directory for files ... 09:12:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:12:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:12:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:12:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:12:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:12:48 INFO - (blobuploader) - INFO - Done attempting. 09:12:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:12:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:12:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:12:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:12:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:12:51 INFO - (blobuploader) - INFO - Done attempting. 09:12:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:12:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:12:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:12:52 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:12:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:12:52 INFO - (blobuploader) - INFO - Done attempting. 09:12:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:12:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:12:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:12:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:12:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:12:53 INFO - (blobuploader) - INFO - Done attempting. 09:12:53 INFO - (blobuploader) - INFO - Iteration through files over. 09:12:53 INFO - Return code: 0 09:12:53 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:12:53 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:12:53 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8384278d41cba809105ddd8a9ba3088ad4a80eb9feafe71fb2f13f80baadb511839bb67ef7a008eaa81e0879fe0ec89a4c8535ae0b91afec50486847d77409b3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/953f0b37a3b95c7a0cb5afdba7e82753f3c7cf696c4009b716f6b29a22007bdf6f57f0497e1a4e3eef8e320f9b7728decc812b8b72d280a8889ae4aaf4279611", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce9c39495ff6532d96c6cc3b32ea5b1ca50bd50efe9a11afa01b9a143c8fbbb816f827f8f568fcad1135e94745139b5fc640e740bccf183adb87e611b7e086b4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f51f0ad0fdb76ce98cceb86ef9290917c31065fcbde817e84b5c2db5eaeeafd87c7647466ec25f440694d9aaa589b1c4bb989086853f84018a1e802dd8a8668e"} 09:12:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:12:53 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:12:53 INFO - Contents: 09:12:53 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8384278d41cba809105ddd8a9ba3088ad4a80eb9feafe71fb2f13f80baadb511839bb67ef7a008eaa81e0879fe0ec89a4c8535ae0b91afec50486847d77409b3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/953f0b37a3b95c7a0cb5afdba7e82753f3c7cf696c4009b716f6b29a22007bdf6f57f0497e1a4e3eef8e320f9b7728decc812b8b72d280a8889ae4aaf4279611", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce9c39495ff6532d96c6cc3b32ea5b1ca50bd50efe9a11afa01b9a143c8fbbb816f827f8f568fcad1135e94745139b5fc640e740bccf183adb87e611b7e086b4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f51f0ad0fdb76ce98cceb86ef9290917c31065fcbde817e84b5c2db5eaeeafd87c7647466ec25f440694d9aaa589b1c4bb989086853f84018a1e802dd8a8668e"} 09:12:53 INFO - Running post-run listener: copy_logs_to_upload_dir 09:12:53 INFO - Copying logs to upload dir... 09:12:53 INFO - mkdir: /builds/slave/test/build/upload/logs 09:12:53 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.102829 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2017-03-23 09:12:53.794010) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-23 09:12:53.797708) ========= 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=eca2ab57546de7b41f256b19000001ef-1490284302.918161-268569705 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/8384278d41cba809105ddd8a9ba3088ad4a80eb9feafe71fb2f13f80baadb511839bb67ef7a008eaa81e0879fe0ec89a4c8535ae0b91afec50486847d77409b3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/953f0b37a3b95c7a0cb5afdba7e82753f3c7cf696c4009b716f6b29a22007bdf6f57f0497e1a4e3eef8e320f9b7728decc812b8b72d280a8889ae4aaf4279611", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce9c39495ff6532d96c6cc3b32ea5b1ca50bd50efe9a11afa01b9a143c8fbbb816f827f8f568fcad1135e94745139b5fc640e740bccf183adb87e611b7e086b4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f51f0ad0fdb76ce98cceb86ef9290917c31065fcbde817e84b5c2db5eaeeafd87c7647466ec25f440694d9aaa589b1c4bb989086853f84018a1e802dd8a8668e"} build_url:https://queue.taskcluster.net/v1/task/LyO05s08SuOSAGNFWIebQw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011228 build_url: 'https://queue.taskcluster.net/v1/task/LyO05s08SuOSAGNFWIebQw/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8384278d41cba809105ddd8a9ba3088ad4a80eb9feafe71fb2f13f80baadb511839bb67ef7a008eaa81e0879fe0ec89a4c8535ae0b91afec50486847d77409b3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/953f0b37a3b95c7a0cb5afdba7e82753f3c7cf696c4009b716f6b29a22007bdf6f57f0497e1a4e3eef8e320f9b7728decc812b8b72d280a8889ae4aaf4279611", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce9c39495ff6532d96c6cc3b32ea5b1ca50bd50efe9a11afa01b9a143c8fbbb816f827f8f568fcad1135e94745139b5fc640e740bccf183adb87e611b7e086b4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f51f0ad0fdb76ce98cceb86ef9290917c31065fcbde817e84b5c2db5eaeeafd87c7647466ec25f440694d9aaa589b1c4bb989086853f84018a1e802dd8a8668e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-23 09:12:53.825769) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-23 09:12:53.826104) ========= 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=eca2ab57546de7b41f256b19000001ef-1490284302.918161-268569705 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004411 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-23 09:12:53.912313) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-23 09:12:53.912631) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-23 09:12:53.912935) ========= ========= Total master_lag: 0.18 =========