builder: pine_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-055 starttime: 1490391416.67 results: success (0) revision: 29bc3de2ac24b25f4719ec6d29a8b85e22458ad3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-24 14:36:56.668944) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-24 14:36:56.669432) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-24 14:36:56.669805) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-24 14:36:56.684617) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-24 14:36:56.684955) ========= 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=bb9b54edd4a927dc735d112f000001d9-1490391416.248526-221435222 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-03-24 14:36:56-- 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% 37.1M=0s 2017-03-24 14:36:56 (37.1 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.141951 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-24 14:36:56.841129) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-24 14:36:56.841500) ========= 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=bb9b54edd4a927dc735d112f000001d9-1490391416.248526-221435222 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013818 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-24 14:36:56.891784) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-24 14:36:56.892180) ========= bash -c 'python archiver_client.py mozharness --repo projects/pine --rev 29bc3de2ac24b25f4719ec6d29a8b85e22458ad3 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo projects/pine --rev 29bc3de2ac24b25f4719ec6d29a8b85e22458ad3 --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=bb9b54edd4a927dc735d112f000001d9-1490391416.248526-221435222 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-03-24 14:36:57,100 truncating revision to first 12 chars 2017-03-24 14:36:57,100 Setting DEBUG logging. 2017-03-24 14:36:57,100 attempt 1/10 2017-03-24 14:36:57,100 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/pine/29bc3de2ac24?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-24 14:36:57,778 unpacking tar archive at: pine-29bc3de2ac24/testing/mozharness/ program finished with exit code 0 elapsedTime=1.006719 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-24 14:36:57.912254) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-24 14:36:57.912613) ========= script_repo_revision: 29bc3de2ac24b25f4719ec6d29a8b85e22458ad3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-24 14:36:57.913013) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-24 14:36:57.913283) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-24 14:36:57.922995) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-03-24 14:36:57.923403) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Pine --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 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', 'Pine', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Pine'] 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=bb9b54edd4a927dc735d112f000001d9-1490391416.248526-221435222 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:36:58 INFO - MultiFileLogger online at 20170324 14:36:58 in /builds/slave/test 14:36:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Pine --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 14:36:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:36:58 INFO - {'append_to_log': False, 14:36:58 INFO - 'base_work_dir': '/builds/slave/test', 14:36:58 INFO - 'blob_upload_branch': 'Pine', 14:36:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:36:58 INFO - 'branch': 'Pine', 14:36:58 INFO - 'buildbot_json_path': 'buildprops.json', 14:36:58 INFO - 'config_files': ('talos/linux_config.py',), 14:36:58 INFO - 'default_actions': ('clobber', 14:36:58 INFO - 'read-buildbot-config', 14:36:58 INFO - 'download-and-extract', 14:36:58 INFO - 'populate-webroot', 14:36:58 INFO - 'create-virtualenv', 14:36:58 INFO - 'install', 14:36:58 INFO - 'run-tests'), 14:36:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:36:58 INFO - 'download_minidump_stackwalk': True, 14:36:58 INFO - 'download_symbols': 'ondemand', 14:36:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:36:58 INFO - 'tooltool.py': '/tools/tooltool.py', 14:36:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:36:58 INFO - '/tools/misc-python/virtualenv.py')}, 14:36:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:36:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:36:58 INFO - 'gecko_profile': False, 14:36:58 INFO - 'gecko_profile_interval': 0, 14:36:58 INFO - 'installer_path': 'installer.exe', 14:36:58 INFO - 'log_level': 'info', 14:36:58 INFO - 'log_name': 'talos', 14:36:58 INFO - 'log_to_console': True, 14:36:58 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:36:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:36:58 INFO - 'opt_config_files': (), 14:36:58 INFO - 'pip_index': False, 14:36:58 INFO - 'suite': 'dromaeojs', 14:36:58 INFO - 'system_bits': '32', 14:36:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:36:58 INFO - 'title': 'talos-linux64-ix-055', 14:36:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:36:58 INFO - 'use_talos_json': True, 14:36:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:36:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:36:58 INFO - 'work_dir': 'build'} 14:36:58 INFO - [mozharness: 2017-03-24 21:36:58.232718Z] Running clobber step. 14:36:58 INFO - Running pre-action listener: _resource_record_pre_action 14:36:58 INFO - Running main action method: clobber 14:36:58 INFO - rmtree: /builds/slave/test/build 14:36:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:36:59 INFO - Running post-action listener: _resource_record_post_action 14:36:59 INFO - [mozharness: 2017-03-24 21:36:59.183415Z] Finished clobber step (success) 14:36:59 INFO - [mozharness: 2017-03-24 21:36:59.183518Z] Running read-buildbot-config step. 14:36:59 INFO - Running pre-action listener: _resource_record_pre_action 14:36:59 INFO - Running main action method: read_buildbot_config 14:36:59 INFO - Using buildbot properties: 14:36:59 INFO - { 14:36:59 INFO - "project": "", 14:36:59 INFO - "product": "firefox", 14:36:59 INFO - "who": "dmosedale@mozilla.com", 14:36:59 INFO - "installer_path": "public/build/target.tar.bz2", 14:36:59 INFO - "builddir": "pine_ubuntu64_hw_test-dromaeojs", 14:36:59 INFO - "repository": "https://hg.mozilla.org/projects/pine/", 14:36:59 INFO - "buildername": "Ubuntu HW 12.04 x64 pine talos dromaeojs", 14:36:59 INFO - "stage_platform": "linux64", 14:36:59 INFO - "basedir": "/builds/slave/test", 14:36:59 INFO - "buildnumber": 3, 14:36:59 INFO - "platform": "ubuntu64_hw", 14:36:59 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 14:36:59 INFO - "slavebuilddir": "test", 14:36:59 INFO - "taskId": "XHtIEQQhTxCi0VJjd9Pi8Q", 14:36:59 INFO - "branch": "pine", 14:36:59 INFO - "script_repo_revision": "default", 14:36:59 INFO - "revision": "29bc3de2ac24b25f4719ec6d29a8b85e22458ad3", 14:36:59 INFO - "slavename": "talos-linux64-ix-055", 14:36:59 INFO - "repo_path": "projects/pine" 14:36:59 INFO - } 14:36:59 INFO - Finding installer, test and symbols from parent task. 14:36:59 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XHtIEQQhTxCi0VJjd9Pi8Q'}, attempt #1 14:36:59 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XHtIEQQhTxCi0VJjd9Pi8Q'}, attempt #1 14:37:00 INFO - Task dependencies: Tp_kezfbS2Od05imaKlSeg 14:37:00 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Tp_kezfbS2Od05imaKlSeg'}, attempt #1 14:37:00 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Tp_kezfbS2Od05imaKlSeg/artifacts/public/build/target.tar.bz2 14:37:00 INFO - Set test_url: https://queue.taskcluster.net/v1/task/Tp_kezfbS2Od05imaKlSeg/artifacts/public/build/test_packages.json 14:37:00 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Tp_kezfbS2Od05imaKlSeg/artifacts/public/build/target.crashreporter-symbols.zip 14:37:00 INFO - Running post-action listener: _resource_record_post_action 14:37:00 INFO - [mozharness: 2017-03-24 21:37:00.580194Z] Finished read-buildbot-config step (success) 14:37:00 INFO - [mozharness: 2017-03-24 21:37:00.580414Z] Running download-and-extract step. 14:37:00 INFO - Running pre-action listener: _resource_record_pre_action 14:37:00 INFO - Running main action method: download_and_extract 14:37:00 INFO - mkdir: /builds/slave/test/build/tests 14:37:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:37:00 INFO - trying https://queue.taskcluster.net/v1/task/Tp_kezfbS2Od05imaKlSeg/artifacts/public/build/target.test_packages.json 14:37:00 INFO - Downloading https://queue.taskcluster.net/v1/task/Tp_kezfbS2Od05imaKlSeg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 14:37:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Tp_kezfbS2Od05imaKlSeg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 14:37:01 INFO - Downloaded 986 bytes. 14:37:01 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 14:37:01 INFO - Using the following test package requirements: 14:37:01 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 14:37:01 INFO - u'common': [u'target.common.tests.zip'], 14:37:01 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 14:37:01 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 14:37:01 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 14:37:01 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 14:37:01 INFO - u'mozbase': [u'target.common.tests.zip'], 14:37:01 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 14:37:01 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 14:37:01 INFO - u'web-platform': [u'target.common.tests.zip', 14:37:01 INFO - u'target.web-platform.tests.zip'], 14:37:01 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 14:37:01 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 14:37:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Tp_kezfbS2Od05imaKlSeg/artifacts/public/build/target.common.tests.zip 14:37:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Tp_kezfbS2Od05imaKlSeg/artifacts/public/build/target.common.tests.zip'}, attempt #1 14:37:01 INFO - Fetch https://queue.taskcluster.net/v1/task/Tp_kezfbS2Od05imaKlSeg/artifacts/public/build/target.common.tests.zip into memory 14:37:02 INFO - Expected file size: 58125643 14:37:02 INFO - Obtained file size: 58125643 14:37:11 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 14:37:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Tp_kezfbS2Od05imaKlSeg/artifacts/public/build/target.common.tests.zip 14:37:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Tp_kezfbS2Od05imaKlSeg/artifacts/public/build/target.common.tests.zip'}, attempt #1 14:37:11 INFO - Fetch https://queue.taskcluster.net/v1/task/Tp_kezfbS2Od05imaKlSeg/artifacts/public/build/target.common.tests.zip into memory 14:37:13 INFO - Expected file size: 58125643 14:37:13 INFO - Obtained file size: 58125643 14:37:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Tp_kezfbS2Od05imaKlSeg/artifacts/public/build/target.talos.tests.zip 14:37:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Tp_kezfbS2Od05imaKlSeg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 14:37:23 INFO - Fetch https://queue.taskcluster.net/v1/task/Tp_kezfbS2Od05imaKlSeg/artifacts/public/build/target.talos.tests.zip into memory 14:37:24 INFO - Expected file size: 14404171 14:37:24 INFO - Obtained file size: 14404171 14:37: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')]} 14:37:24 INFO - trying https://queue.taskcluster.net/v1/task/Tp_kezfbS2Od05imaKlSeg/artifacts/public/build/target.tar.bz2 14:37:24 INFO - Downloading https://queue.taskcluster.net/v1/task/Tp_kezfbS2Od05imaKlSeg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 14:37:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Tp_kezfbS2Od05imaKlSeg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 14:37:25 INFO - Downloaded 62597721 bytes. 14:37:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Tp_kezfbS2Od05imaKlSeg/artifacts/public/build/target.tar.bz2 14:37:25 INFO - mkdir: /builds/slave/test/properties 14:37:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:37:25 INFO - Writing to file /builds/slave/test/properties/build_url 14:37:25 INFO - Contents: 14:37:25 INFO - build_url:https://queue.taskcluster.net/v1/task/Tp_kezfbS2Od05imaKlSeg/artifacts/public/build/target.tar.bz2 14:37:25 INFO - Running post-action listener: _resource_record_post_action 14:37:25 INFO - Running post-action listener: set_extra_try_arguments 14:37:25 INFO - [mozharness: 2017-03-24 21:37:25.955171Z] Finished download-and-extract step (success) 14:37:25 INFO - [mozharness: 2017-03-24 21:37:25.955268Z] Running populate-webroot step. 14:37:25 INFO - Running pre-action listener: _resource_record_pre_action 14:37:25 INFO - Running main action method: populate_webroot 14:37:25 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:37:25 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:37:25 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:37:25 INFO - '--tppagecycles', 14:37:25 INFO - '7'], 14:37:25 INFO - 'tests': ['tsvgm']}}, 14:37:25 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:37:25 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:37:25 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:37:25 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:37:25 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:37:25 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:37:25 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 14:37:25 INFO - 'talos_options': ['--disable-e10s'], 14:37:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:37:25 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:37:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:37:25 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 14:37:25 INFO - 'talos_options': ['--disable-e10s'], 14:37:25 INFO - 'tests': ['damp', 'tps']}, 14:37:25 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:37:25 INFO - 'tests': ['damp', 'tps']}, 14:37:25 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:37:25 INFO - 'tests': ['dromaeo_dom']}, 14:37:25 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:37:25 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:37:25 INFO - 'tests': ['basic_compositor_video']}, 14:37:25 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:37:25 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:37:25 INFO - 'tests': ['a11yr', 14:37:25 INFO - 'ts_paint', 14:37:25 INFO - 'tpaint', 14:37:25 INFO - 'sessionrestore', 14:37:25 INFO - 'sessionrestore_no_auto_restore', 14:37:25 INFO - 'tabpaint']}, 14:37:25 INFO - 'other-e10s': {'tests': ['a11yr', 14:37:25 INFO - 'ts_paint', 14:37:25 INFO - 'tpaint', 14:37:25 INFO - 'sessionrestore', 14:37:25 INFO - 'sessionrestore_no_auto_restore', 14:37:25 INFO - 'tabpaint']}, 14:37:25 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:37:25 INFO - 'ts_paint', 14:37:25 INFO - 'tpaint', 14:37:25 INFO - 'sessionrestore', 14:37:25 INFO - 'sessionrestore_no_auto_restore', 14:37:25 INFO - 'tabpaint']}, 14:37:25 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:37:25 INFO - 'ts_paint', 14:37:25 INFO - 'tpaint', 14:37:25 INFO - 'sessionrestore', 14:37:25 INFO - 'sessionrestore_no_auto_restore', 14:37:25 INFO - 'tabpaint']}, 14:37:25 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:37:25 INFO - 'tests': ['a11yr', 14:37:25 INFO - 'ts_paint', 14:37:25 INFO - 'tpaint', 14:37:25 INFO - 'sessionrestore', 14:37:25 INFO - 'sessionrestore_no_auto_restore', 14:37:25 INFO - 'tabpaint']}, 14:37:25 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:37:25 INFO - 'tests': ['a11yr', 14:37:25 INFO - 'ts_paint', 14:37:25 INFO - 'tpaint', 14:37:25 INFO - 'sessionrestore', 14:37:25 INFO - 'sessionrestore_no_auto_restore', 14:37:25 INFO - 'tabpaint']}, 14:37:25 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:37:25 INFO - 'tests': ['tsvgx', 14:37:25 INFO - 'tsvgr_opacity', 14:37:25 INFO - 'tart', 14:37:25 INFO - 'tscrollx', 14:37:25 INFO - 'cart', 14:37:25 INFO - 'tsvg_static']}, 14:37:25 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:37:25 INFO - 'tsvgr_opacity', 14:37:25 INFO - 'tart', 14:37:25 INFO - 'tscrollx', 14:37:25 INFO - 'cart', 14:37:25 INFO - 'tsvg_static']}, 14:37:25 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 14:37:25 INFO - 'talos_options': ['--disable-e10s'], 14:37:25 INFO - 'tests': ['tp5o']}, 14:37:25 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:37:25 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 14:37:25 INFO - 'talos_options': ['--disable-e10s', 14:37:25 INFO - '--xperf_path', 14:37:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:37:25 INFO - 'tests': ['tp5n']}, 14:37:25 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:37:25 INFO - 'talos_options': ['--xperf_path', 14:37:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:37:25 INFO - 'tests': ['tp5n']}}, 14:37:25 INFO - 'talos.zip': {'path': '', 14:37:25 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:37:25 INFO - Running post-action listener: _resource_record_post_action 14:37:25 INFO - [mozharness: 2017-03-24 21:37:25.968345Z] Finished populate-webroot step (success) 14:37:25 INFO - [mozharness: 2017-03-24 21:37:25.968436Z] Running create-virtualenv step. 14:37:25 INFO - Running pre-action listener: _resource_record_pre_action 14:37:25 INFO - Running main action method: create_virtualenv 14:37:25 INFO - Creating virtualenv /builds/slave/test/build/venv 14:37:25 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 14:37:25 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:37:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:37:26 INFO - Using real prefix '/usr' 14:37:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:37:26 INFO - Installing distribute.............................................................................................................................................................................................done. 14:37:28 INFO - Installing pip.................done. 14:37:28 INFO - Return code: 0 14:37:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:37:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:37:28 INFO - Reading from file tmpfile_stdout 14:37:28 INFO - Output received: 14:37:28 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 14:37:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:37: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')]} 14:37:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:37:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:37: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')]} 14:37:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:37:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:37: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fe21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x201fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2280310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x227d400>, '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 0x227e3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x227e840>, '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': 'bb9b54edd4a927dc735d112f000001d9-1490391416.248526-221435222', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:37: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', 'pip>=1.5'] in /builds/slave/test/build 14:37: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 pip>=1.5 14:37:28 INFO - Using env: {'DISPLAY': ':0', 14:37:28 INFO - 'HOME': '/home/cltbld', 14:37:28 INFO - 'LANG': 'en_US.UTF-8', 14:37:28 INFO - 'LANGUAGE': 'en_US:en', 14:37:28 INFO - 'LOGNAME': 'cltbld', 14:37:28 INFO - 'MAIL': '/var/mail/cltbld', 14:37:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:37:28 INFO - 'MOZ_NO_REMOTE': '1', 14:37:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:37:28 INFO - 'NO_EM_RESTART': '1', 14:37:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:37:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:37:28 INFO - 'PWD': '/builds/slave/test', 14:37:28 INFO - 'SHELL': '/bin/bash', 14:37:28 INFO - 'SHLVL': '1', 14:37:28 INFO - 'TERM': 'linux', 14:37:28 INFO - 'TMOUT': '86400', 14:37:28 INFO - 'USER': 'cltbld', 14:37:28 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1490391416.248526-221435222', 14:37:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:37:28 INFO - '_': '/tools/buildbot/bin/python'} 14:37:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:37:28 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 14:37:28 INFO - Cleaning up... 14:37:28 INFO - Return code: 0 14:37:28 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:37: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')]} 14:37:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:37:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:37: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')]} 14:37:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:37:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:37: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', '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 0x1fe21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x201fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2280310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x227d400>, '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 0x227e3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x227e840>, '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': 'bb9b54edd4a927dc735d112f000001d9-1490391416.248526-221435222', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:37: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', 'psutil>=3.1.1'] in /builds/slave/test/build 14:37: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 psutil>=3.1.1 14:37:28 INFO - Using env: {'DISPLAY': ':0', 14:37:28 INFO - 'HOME': '/home/cltbld', 14:37:28 INFO - 'LANG': 'en_US.UTF-8', 14:37:28 INFO - 'LANGUAGE': 'en_US:en', 14:37:28 INFO - 'LOGNAME': 'cltbld', 14:37:28 INFO - 'MAIL': '/var/mail/cltbld', 14:37:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:37:28 INFO - 'MOZ_NO_REMOTE': '1', 14:37:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:37:28 INFO - 'NO_EM_RESTART': '1', 14:37:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:37:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:37:28 INFO - 'PWD': '/builds/slave/test', 14:37:28 INFO - 'SHELL': '/bin/bash', 14:37:28 INFO - 'SHLVL': '1', 14:37:28 INFO - 'TERM': 'linux', 14:37:28 INFO - 'TMOUT': '86400', 14:37:28 INFO - 'USER': 'cltbld', 14:37:28 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1490391416.248526-221435222', 14:37:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:37:28 INFO - '_': '/tools/buildbot/bin/python'} 14:37:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:37:29 INFO - Downloading/unpacking psutil>=3.1.1 14:37:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:37:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:37:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:37:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:37:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:37:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:37:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:37:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:37:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:37:31 INFO - Installing collected packages: psutil 14:37:31 INFO - Running setup.py install for psutil 14:37:31 INFO - building 'psutil._psutil_linux' extension 14:37:31 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 14:37:31 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 14:37:31 INFO - building 'psutil._psutil_posix' extension 14:37:31 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 14:37:31 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 14:37:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:37:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:37:31 INFO - Successfully installed psutil 14:37:31 INFO - Cleaning up... 14:37:31 INFO - Return code: 0 14:37:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:37: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')]} 14:37:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:37:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:37: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')]} 14:37:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:37:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:37: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fe21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x201fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2280310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x227d400>, '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 0x227e3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x227e840>, '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': 'bb9b54edd4a927dc735d112f000001d9-1490391416.248526-221435222', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:37: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:37: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 mozsystemmonitor==0.3 14:37:31 INFO - Using env: {'DISPLAY': ':0', 14:37:31 INFO - 'HOME': '/home/cltbld', 14:37:31 INFO - 'LANG': 'en_US.UTF-8', 14:37:31 INFO - 'LANGUAGE': 'en_US:en', 14:37:31 INFO - 'LOGNAME': 'cltbld', 14:37:31 INFO - 'MAIL': '/var/mail/cltbld', 14:37:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:37:31 INFO - 'MOZ_NO_REMOTE': '1', 14:37:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:37:31 INFO - 'NO_EM_RESTART': '1', 14:37:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:37:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:37:31 INFO - 'PWD': '/builds/slave/test', 14:37:31 INFO - 'SHELL': '/bin/bash', 14:37:31 INFO - 'SHLVL': '1', 14:37:31 INFO - 'TERM': 'linux', 14:37:31 INFO - 'TMOUT': '86400', 14:37:31 INFO - 'USER': 'cltbld', 14:37:31 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1490391416.248526-221435222', 14:37:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:37:31 INFO - '_': '/tools/buildbot/bin/python'} 14:37:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:37:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:37:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:37:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:37:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:37:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:37:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:37:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:37:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:37:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:37:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:37:34 INFO - Installing collected packages: mozsystemmonitor 14:37:34 INFO - Running setup.py install for mozsystemmonitor 14:37:34 INFO - Successfully installed mozsystemmonitor 14:37:34 INFO - Cleaning up... 14:37:34 INFO - Return code: 0 14:37:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:37: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')]} 14:37:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:37:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:37: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')]} 14:37:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:37:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:37: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', '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 0x1fe21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x201fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2280310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x227d400>, '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 0x227e3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x227e840>, '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': 'bb9b54edd4a927dc735d112f000001d9-1490391416.248526-221435222', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:37: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 14:37: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 jsonschema==2.5.1 14:37:34 INFO - Using env: {'DISPLAY': ':0', 14:37:34 INFO - 'HOME': '/home/cltbld', 14:37:34 INFO - 'LANG': 'en_US.UTF-8', 14:37:34 INFO - 'LANGUAGE': 'en_US:en', 14:37:34 INFO - 'LOGNAME': 'cltbld', 14:37:34 INFO - 'MAIL': '/var/mail/cltbld', 14:37:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:37:34 INFO - 'MOZ_NO_REMOTE': '1', 14:37:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:37:34 INFO - 'NO_EM_RESTART': '1', 14:37:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:37:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:37:34 INFO - 'PWD': '/builds/slave/test', 14:37:34 INFO - 'SHELL': '/bin/bash', 14:37:34 INFO - 'SHLVL': '1', 14:37:34 INFO - 'TERM': 'linux', 14:37:34 INFO - 'TMOUT': '86400', 14:37:34 INFO - 'USER': 'cltbld', 14:37:34 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1490391416.248526-221435222', 14:37:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:37:34 INFO - '_': '/tools/buildbot/bin/python'} 14:37:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:37:34 INFO - Downloading/unpacking jsonschema==2.5.1 14:37: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 14:37: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 14:37: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 14:37: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 14:37: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 14:37: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 14:37:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:37:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:37: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 14:37: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 14:37: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 14:37: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 14:37: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 14:37: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 14:37:36 INFO - Downloading functools32-3.2.3-2.tar.gz 14:37:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:37:36 INFO - warning: no files found matching '*.txt' 14:37:36 INFO - no previously-included directories found matching 'build' 14:37:36 INFO - no previously-included directories found matching 'dist' 14:37:36 INFO - no previously-included directories found matching '.git*' 14:37:36 INFO - Installing collected packages: jsonschema, functools32 14:37:36 INFO - Running setup.py install for functools32 14:37:36 INFO - warning: no files found matching '*.txt' 14:37:36 INFO - no previously-included directories found matching 'build' 14:37:36 INFO - no previously-included directories found matching 'dist' 14:37:36 INFO - no previously-included directories found matching '.git*' 14:37:36 INFO - Successfully installed jsonschema functools32 14:37:36 INFO - Cleaning up... 14:37:37 INFO - Return code: 0 14:37:37 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:37: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')]} 14:37:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:37:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:37: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')]} 14:37:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:37:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:37:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1fe21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x201fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2280310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x227d400>, '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 0x227e3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x227e840>, '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': 'bb9b54edd4a927dc735d112f000001d9-1490391416.248526-221435222', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:37:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 14:37:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 14:37:37 INFO - Using env: {'DISPLAY': ':0', 14:37:37 INFO - 'HOME': '/home/cltbld', 14:37:37 INFO - 'LANG': 'en_US.UTF-8', 14:37:37 INFO - 'LANGUAGE': 'en_US:en', 14:37:37 INFO - 'LOGNAME': 'cltbld', 14:37:37 INFO - 'MAIL': '/var/mail/cltbld', 14:37:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:37:37 INFO - 'MOZ_NO_REMOTE': '1', 14:37:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:37:37 INFO - 'NO_EM_RESTART': '1', 14:37:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:37:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:37:37 INFO - 'PWD': '/builds/slave/test', 14:37:37 INFO - 'SHELL': '/bin/bash', 14:37:37 INFO - 'SHLVL': '1', 14:37:37 INFO - 'TERM': 'linux', 14:37:37 INFO - 'TMOUT': '86400', 14:37:37 INFO - 'USER': 'cltbld', 14:37:37 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1490391416.248526-221435222', 14:37:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:37:37 INFO - '_': '/tools/buildbot/bin/python'} 14:37:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:37:37 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:37:37 INFO - Cleaning up... 14:37:37 INFO - Return code: 0 14:37:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:37: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')]} 14:37:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:37:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:37: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')]} 14:37:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:37:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:37:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1fe21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x201fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2280310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x227d400>, '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 0x227e3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x227e840>, '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': 'bb9b54edd4a927dc735d112f000001d9-1490391416.248526-221435222', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:37:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:37:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:37:37 INFO - Using env: {'DISPLAY': ':0', 14:37:37 INFO - 'HOME': '/home/cltbld', 14:37:37 INFO - 'LANG': 'en_US.UTF-8', 14:37:37 INFO - 'LANGUAGE': 'en_US:en', 14:37:37 INFO - 'LOGNAME': 'cltbld', 14:37:37 INFO - 'MAIL': '/var/mail/cltbld', 14:37:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:37:37 INFO - 'MOZ_NO_REMOTE': '1', 14:37:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:37:37 INFO - 'NO_EM_RESTART': '1', 14:37:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:37:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:37:37 INFO - 'PWD': '/builds/slave/test', 14:37:37 INFO - 'SHELL': '/bin/bash', 14:37:37 INFO - 'SHLVL': '1', 14:37:37 INFO - 'TERM': 'linux', 14:37:37 INFO - 'TMOUT': '86400', 14:37:37 INFO - 'USER': 'cltbld', 14:37:37 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1490391416.248526-221435222', 14:37:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:37:37 INFO - '_': '/tools/buildbot/bin/python'} 14:37:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:37:37 INFO - Downloading/unpacking blobuploader==1.2.4 14:37:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:37:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:37:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:37:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:37:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:37:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:37:39 INFO - Downloading blobuploader-1.2.4.tar.gz 14:37:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:37:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:37:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:37:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:37:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:37:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:37:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:37:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:37:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:37:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:37:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:37:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:37:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:37:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:37:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:37:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:37:40 INFO - Downloading docopt-0.6.1.tar.gz 14:37:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:37:40 INFO - Installing collected packages: blobuploader, requests, docopt 14:37:40 INFO - Running setup.py install for blobuploader 14:37:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:37:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:37:40 INFO - Running setup.py install for requests 14:37:40 INFO - Running setup.py install for docopt 14:37:40 INFO - Successfully installed blobuploader requests docopt 14:37:40 INFO - Cleaning up... 14:37:40 INFO - Return code: 0 14:37:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:37:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:37:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:37:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:37:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:37:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:37:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:37:40 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 0x1fe21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x201fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2280310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x227d400>, '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 0x227e3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x227e840>, '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': 'bb9b54edd4a927dc735d112f000001d9-1490391416.248526-221435222', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:37:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:37:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:37:40 INFO - Using env: {'DISPLAY': ':0', 14:37:40 INFO - 'HOME': '/home/cltbld', 14:37:40 INFO - 'LANG': 'en_US.UTF-8', 14:37:40 INFO - 'LANGUAGE': 'en_US:en', 14:37:40 INFO - 'LOGNAME': 'cltbld', 14:37:40 INFO - 'MAIL': '/var/mail/cltbld', 14:37:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:37:40 INFO - 'MOZ_NO_REMOTE': '1', 14:37:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:37:40 INFO - 'NO_EM_RESTART': '1', 14:37:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:37:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:37:40 INFO - 'PWD': '/builds/slave/test', 14:37:40 INFO - 'SHELL': '/bin/bash', 14:37:40 INFO - 'SHLVL': '1', 14:37:40 INFO - 'TERM': 'linux', 14:37:40 INFO - 'TMOUT': '86400', 14:37:40 INFO - 'USER': 'cltbld', 14:37:40 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1490391416.248526-221435222', 14:37:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:37:40 INFO - '_': '/tools/buildbot/bin/python'} 14:37:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:37:41 INFO - Running setup.py (path:/tmp/pip-BZh8kP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:37:41 INFO - Running setup.py (path:/tmp/pip-VmXqmI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:37:41 INFO - Running setup.py (path:/tmp/pip-sJWdKG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:37:41 INFO - Running setup.py (path:/tmp/pip-XpMHwx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:37:41 INFO - Running setup.py (path:/tmp/pip-rvrBzT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:37:41 INFO - Running setup.py (path:/tmp/pip-87ULEr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:37:41 INFO - Running setup.py (path:/tmp/pip-1vASnS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:37:41 INFO - Running setup.py (path:/tmp/pip-lmgyxe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:37:41 INFO - Running setup.py (path:/tmp/pip-vPeEWs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:37:41 INFO - Running setup.py (path:/tmp/pip-UUhFo1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:37:41 INFO - Running setup.py (path:/tmp/pip-l6if75-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:37:41 INFO - Running setup.py (path:/tmp/pip-P5ZmK5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:37:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:37:41 INFO - Running setup.py (path:/tmp/pip-nM3LRE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:37:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:37:42 INFO - Running setup.py (path:/tmp/pip-wkKqaz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:37:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:37:42 INFO - Running setup.py (path:/tmp/pip-qpgwXE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:37:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:37:42 INFO - Running setup.py (path:/tmp/pip-a0y5hJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:37:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:37:42 INFO - Running setup.py (path:/tmp/pip-rtg__b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:37:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:37:42 INFO - Running setup.py install for manifestparser 14:37:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:37:42 INFO - Running setup.py install for mozcrash 14:37:42 INFO - Running setup.py install for mozdebug 14:37:42 INFO - Running setup.py install for mozdevice 14:37:42 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:37:42 INFO - Running setup.py install for mozfile 14:37:42 INFO - Running setup.py install for mozhttpd 14:37:42 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:37:42 INFO - Running setup.py install for mozinfo 14:37:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:37:42 INFO - Running setup.py install for mozInstall 14:37:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:37:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:37:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:37:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:37:43 INFO - Running setup.py install for mozleak 14:37:43 INFO - Running setup.py install for mozlog 14:37:43 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:37:43 INFO - Running setup.py install for moznetwork 14:37:43 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:37:43 INFO - Running setup.py install for mozprocess 14:37:43 INFO - Running setup.py install for mozprofile 14:37:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:37:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:37:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:37:43 INFO - Running setup.py install for mozrunner 14:37:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:37:43 INFO - Running setup.py install for mozscreenshot 14:37:43 INFO - Running setup.py install for moztest 14:37:43 INFO - Running setup.py install for mozversion 14:37:44 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:37:44 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:37:44 INFO - Cleaning up... 14:37:44 INFO - Return code: 0 14:37:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:37:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:37:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:37:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:37:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:37:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:37:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:37:44 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 0x1fe21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x201fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2280310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x227d400>, '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 0x227e3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x227e840>, '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': 'bb9b54edd4a927dc735d112f000001d9-1490391416.248526-221435222', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:37:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:37:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:37:44 INFO - Using env: {'DISPLAY': ':0', 14:37:44 INFO - 'HOME': '/home/cltbld', 14:37:44 INFO - 'LANG': 'en_US.UTF-8', 14:37:44 INFO - 'LANGUAGE': 'en_US:en', 14:37:44 INFO - 'LOGNAME': 'cltbld', 14:37:44 INFO - 'MAIL': '/var/mail/cltbld', 14:37:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:37:44 INFO - 'MOZ_NO_REMOTE': '1', 14:37:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:37:44 INFO - 'NO_EM_RESTART': '1', 14:37:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:37:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:37:44 INFO - 'PWD': '/builds/slave/test', 14:37:44 INFO - 'SHELL': '/bin/bash', 14:37:44 INFO - 'SHLVL': '1', 14:37:44 INFO - 'TERM': 'linux', 14:37:44 INFO - 'TMOUT': '86400', 14:37:44 INFO - 'USER': 'cltbld', 14:37:44 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1490391416.248526-221435222', 14:37:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:37:44 INFO - '_': '/tools/buildbot/bin/python'} 14:37:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:37:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:37:44 INFO - Running setup.py (path:/tmp/pip-xiHJ65-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:37:44 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 14:37:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:37:44 INFO - Running setup.py (path:/tmp/pip-71_t84-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:37:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:37:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:37:44 INFO - Running setup.py (path:/tmp/pip-36iVvT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:37:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:37:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:37:44 INFO - Running setup.py (path:/tmp/pip-uUJZ1f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:37:44 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)) 14:37:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:37:44 INFO - Running setup.py (path:/tmp/pip-38UA5L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:37:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 14:37:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:37:44 INFO - Running setup.py (path:/tmp/pip-vbScaO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:37:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 14:37:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:37:44 INFO - Running setup.py (path:/tmp/pip-ifoT7K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:37:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 14:37:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:37:44 INFO - Running setup.py (path:/tmp/pip-Nxjj_O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:37:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 14:37:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:37:44 INFO - Running setup.py (path:/tmp/pip-zlOXIW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:37:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 14:37:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:37:44 INFO - Running setup.py (path:/tmp/pip-c6bHRN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:37:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:37:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:37:45 INFO - Running setup.py (path:/tmp/pip-oTRqRs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:37:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 14:37:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:37:45 INFO - Running setup.py (path:/tmp/pip-mp3Zza-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:37:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 14:37:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:37:45 INFO - Running setup.py (path:/tmp/pip-6sHr0Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:37:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 14:37:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:37:45 INFO - Running setup.py (path:/tmp/pip-FzrEFb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:37:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 14:37:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:37:45 INFO - Running setup.py (path:/tmp/pip-BhVaN7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:37:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 14:37:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:37:45 INFO - Running setup.py (path:/tmp/pip-lQGqGZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:37:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 14:37:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:37:45 INFO - Running setup.py (path:/tmp/pip-cA7UYY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:37:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 14:37:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:37:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:37:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:37:45 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)) 14:37:45 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)) 14:37:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:37:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:37:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:37:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:37:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:37:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:37:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:37:47 INFO - Downloading blessings-1.6.tar.gz 14:37:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:37:47 INFO - Installing collected packages: blessings 14:37:47 INFO - Running setup.py install for blessings 14:37:47 INFO - Successfully installed blessings 14:37:47 INFO - Cleaning up... 14:37:47 INFO - Return code: 0 14:37:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:37:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:37:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:37:48 INFO - Reading from file tmpfile_stdout 14:37:48 INFO - Current package versions: 14:37:48 INFO - argparse == 1.2.1 14:37:48 INFO - blessings == 1.6 14:37:48 INFO - blobuploader == 1.2.4 14:37:48 INFO - docopt == 0.6.1 14:37:48 INFO - functools32 == 3.2.3-2 14:37:48 INFO - jsonschema == 2.5.1 14:37:48 INFO - manifestparser == 1.1 14:37:48 INFO - mozInstall == 1.12 14:37:48 INFO - mozcrash == 1.0 14:37:48 INFO - mozdebug == 0.1 14:37:48 INFO - mozdevice == 0.49 14:37:48 INFO - mozfile == 1.2 14:37:48 INFO - mozhttpd == 0.7 14:37:48 INFO - mozinfo == 0.9 14:37:48 INFO - mozleak == 0.1 14:37:48 INFO - mozlog == 3.4 14:37:48 INFO - moznetwork == 0.27 14:37:48 INFO - mozprocess == 0.25 14:37:48 INFO - mozprofile == 0.28 14:37:48 INFO - mozrunner == 6.13 14:37:48 INFO - mozscreenshot == 0.1 14:37:48 INFO - mozsystemmonitor == 0.3 14:37:48 INFO - moztest == 0.8 14:37:48 INFO - mozversion == 1.4 14:37:48 INFO - psutil == 3.1.1 14:37:48 INFO - requests == 1.2.3 14:37:48 INFO - wsgiref == 0.1.2 14:37:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:37:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:37:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:37:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:37:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:37:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:37:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:37:48 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 0x1fe21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x201fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2280310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x227d400>, '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 0x227e3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x227e840>, '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': 'bb9b54edd4a927dc735d112f000001d9-1490391416.248526-221435222', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:37:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 14:37:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:37:48 INFO - Using env: {'DISPLAY': ':0', 14:37:48 INFO - 'HOME': '/home/cltbld', 14:37:48 INFO - 'LANG': 'en_US.UTF-8', 14:37:48 INFO - 'LANGUAGE': 'en_US:en', 14:37:48 INFO - 'LOGNAME': 'cltbld', 14:37:48 INFO - 'MAIL': '/var/mail/cltbld', 14:37:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:37:48 INFO - 'MOZ_NO_REMOTE': '1', 14:37:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:37:48 INFO - 'NO_EM_RESTART': '1', 14:37:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:37:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:37:48 INFO - 'PWD': '/builds/slave/test', 14:37:48 INFO - 'SHELL': '/bin/bash', 14:37:48 INFO - 'SHLVL': '1', 14:37:48 INFO - 'TERM': 'linux', 14:37:48 INFO - 'TMOUT': '86400', 14:37:48 INFO - 'USER': 'cltbld', 14:37:48 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1490391416.248526-221435222', 14:37:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:37:48 INFO - '_': '/tools/buildbot/bin/python'} 14:37:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:37:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:37:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 14:37:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 14:37:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:37:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 14:37:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 14:37:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 14:37:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 14:37:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 14:37:48 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:37:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:37:48 INFO - Cleaning up... 14:37:48 INFO - Return code: 0 14:37:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:37:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:37:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:37:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:37:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:37:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:37:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:37:48 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 0x1fe21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x201fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2280310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x227d400>, '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 0x227e3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x227e840>, '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': 'bb9b54edd4a927dc735d112f000001d9-1490391416.248526-221435222', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:37:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:37:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:37:48 INFO - Using env: {'DISPLAY': ':0', 14:37:48 INFO - 'HOME': '/home/cltbld', 14:37:48 INFO - 'LANG': 'en_US.UTF-8', 14:37:48 INFO - 'LANGUAGE': 'en_US:en', 14:37:48 INFO - 'LOGNAME': 'cltbld', 14:37:48 INFO - 'MAIL': '/var/mail/cltbld', 14:37:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:37:48 INFO - 'MOZ_NO_REMOTE': '1', 14:37:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:37:48 INFO - 'NO_EM_RESTART': '1', 14:37:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:37:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:37:48 INFO - 'PWD': '/builds/slave/test', 14:37:48 INFO - 'SHELL': '/bin/bash', 14:37:48 INFO - 'SHLVL': '1', 14:37:48 INFO - 'TERM': 'linux', 14:37:48 INFO - 'TMOUT': '86400', 14:37:48 INFO - 'USER': 'cltbld', 14:37:48 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1490391416.248526-221435222', 14:37:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:37:48 INFO - '_': '/tools/buildbot/bin/python'} 14:37:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:37:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:37:48 INFO - Cleaning up... 14:37:48 INFO - Return code: 0 14:37:48 INFO - Running post-action listener: _resource_record_post_action 14:37:48 INFO - Running post-action listener: _start_resource_monitoring 14:37:48 INFO - Starting resource monitoring. 14:37:48 INFO - [mozharness: 2017-03-24 21:37:48.487338Z] Finished create-virtualenv step (success) 14:37:48 INFO - [mozharness: 2017-03-24 21:37:48.487850Z] Running install step. 14:37:48 INFO - Running pre-action listener: _resource_record_pre_action 14:37:48 INFO - Running main action method: install 14:37:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:37:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:37:48 INFO - Reading from file tmpfile_stdout 14:37:48 INFO - Detecting whether we're running mozinstall >=1.0... 14:37:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:37:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:37:48 INFO - Reading from file tmpfile_stdout 14:37:48 INFO - Output received: 14:37:48 INFO - Usage: mozinstall [options] installer 14:37:48 INFO - Options: 14:37:48 INFO - -h, --help show this help message and exit 14:37:48 INFO - -d DEST, --destination=DEST 14:37:48 INFO - Directory to install application into. [default: 14:37:48 INFO - "/builds/slave/test"] 14:37:48 INFO - --app=APP Application being installed. [default: firefox] 14:37:48 INFO - mkdir: /builds/slave/test/build/application 14:37:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 14:37:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 14:38:04 INFO - Reading from file tmpfile_stdout 14:38:04 INFO - Output received: 14:38:04 INFO - /builds/slave/test/build/application/firefox/firefox 14:38:04 INFO - Running post-action listener: _resource_record_post_action 14:38:04 INFO - [mozharness: 2017-03-24 21:38:04.947792Z] Finished install step (success) 14:38:04 INFO - [mozharness: 2017-03-24 21:38:04.948068Z] Running run-tests step. 14:38:04 INFO - Running pre-action listener: _resource_record_pre_action 14:38:04 INFO - Running main action method: run_tests 14:38:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:38:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:38:04 INFO - Python 2.7.3 14:38:04 INFO - Return code: 0 14:38:04 INFO - grabbing minidump binary from tooltool 14:38:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:38:04 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 0x227d400>, '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 0x227e3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x227e840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:38:04 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 14:38:04 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 14:38:04 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 14:38:05 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:38:05 INFO - Return code: 0 14:38:05 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:38:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:38:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:38:05 INFO - ENV: RUST_BACKTRACE is now 1 14:38:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:38:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:38:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Tp_kezfbS2Od05imaKlSeg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-055', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 14:38:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Pine --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Tp_kezfbS2Od05imaKlSeg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-055 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 14:38:05 INFO - Using env: {'DISPLAY': ':0', 14:38:05 INFO - 'HOME': '/home/cltbld', 14:38:05 INFO - 'LANG': 'en_US.UTF-8', 14:38:05 INFO - 'LANGUAGE': 'en_US:en', 14:38:05 INFO - 'LOGNAME': 'cltbld', 14:38:05 INFO - 'MAIL': '/var/mail/cltbld', 14:38:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:38:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:38:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:38:05 INFO - 'MOZ_NO_REMOTE': '1', 14:38:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:38:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:38:05 INFO - 'NO_EM_RESTART': '1', 14:38:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:38:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:38:05 INFO - 'PWD': '/builds/slave/test', 14:38:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:38:05 INFO - 'RUST_BACKTRACE': '1', 14:38:05 INFO - 'SHELL': '/bin/bash', 14:38:05 INFO - 'SHLVL': '1', 14:38:05 INFO - 'TERM': 'linux', 14:38:05 INFO - 'TMOUT': '86400', 14:38:05 INFO - 'USER': 'cltbld', 14:38:05 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1490391416.248526-221435222', 14:38:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:38:05 INFO - '_': '/tools/buildbot/bin/python'} 14:38:05 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Tp_kezfbS2Od05imaKlSeg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-055', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 14:38:05 INFO - mozversion application_buildid: 20170324205342 14:38:05 INFO - mozversion application_changeset: 29bc3de2ac24b25f4719ec6d29a8b85e22458ad3 14:38:05 INFO - mozversion application_display_name: Nightly 14:38:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:38:05 INFO - mozversion application_name: Firefox 14:38:05 INFO - mozversion application_remotingname: firefox 14:38:05 INFO - mozversion application_repository: https://hg.mozilla.org/projects/pine 14:38:05 INFO - mozversion application_vendor: Mozilla 14:38:05 INFO - mozversion application_version: 55.0a1 14:38:05 INFO - mozversion platform_buildid: 20170324205342 14:38:05 INFO - mozversion platform_changeset: 29bc3de2ac24b25f4719ec6d29a8b85e22458ad3 14:38:05 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/pine 14:38:05 INFO - mozversion platform_version: 55.0a1 14:38:05 INFO - using testdate: 1490391485 14:38:05 INFO - actual date: 1490391485 14:38:05 INFO - starting webserver on 'localhost:50170' 14:38:05 INFO - SUITE-START | Running 2 tests 14:38:05 INFO - TEST-START | dromaeo_css 14:38:05 INFO - operating with platform_type : linux_ 14:38:05 INFO - Initialising browser for dromaeo_css test... 14:38:05 INFO - TEST-INFO | started process 4119 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6F0Njx/profile http://localhost:50170/getInfo.html) 14:38:09 INFO - PID 4119 | __metrics Screen width/height:1600/1200 14:38:09 INFO - PID 4119 | colorDepth:24 14:38:09 INFO - PID 4119 | Browser inner width/height: 1024/697 14:38:09 INFO - PID 4119 | __metrics 14:38:10 INFO - PID 4119 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:38:10 INFO - PID 4119 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:38:11 INFO - TEST-INFO | 4119: exit 0 14:38:11 INFO - Browser initialized. 14:38:11 INFO - Running cycle 1/1 for dromaeo_css test... 14:38:11 INFO - TEST-INFO | started process 4208 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6F0Njx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:38:23 INFO - PID 4208 | RSS: Main: 150351872 14:38:23 INFO - PID 4208 | 14:41:29 INFO - PID 4208 | Cycle 1(1): loaded http://localhost:50170/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50170/tests/dromaeo/cssquery-ext.html) 14:41:31 INFO - PID 4208 | RSS: Main: 255979520 14:41:31 INFO - PID 4208 | 14:43:12 INFO - PID 4208 | ************************* 14:43:12 INFO - PID 4208 | A coding exception was thrown and uncaught in a Task. 14:43:12 INFO - PID 4208 | 14:43:12 ERROR - PID 4208 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:43:12 INFO - PID 4208 | Full stack: 14:43:12 INFO - PID 4208 | ************************* 14:44:33 INFO - PID 4208 | Cycle 1(1): loaded http://localhost:50170/tests/dromaeo/cssquery-ext.html (next: http://localhost:50170/tests/dromaeo/cssquery-jquery.html) 14:44:34 INFO - PID 4208 | RSS: Main: 230789120 14:44:34 INFO - PID 4208 | 14:47:36 INFO - PID 4208 | Cycle 1(1): loaded http://localhost:50170/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50170/tests/dromaeo/cssquery-mootools.html) 14:47:37 INFO - PID 4208 | RSS: Main: 219848704 14:47:37 INFO - PID 4208 | 14:50:39 INFO - PID 4208 | Cycle 1(1): loaded http://localhost:50170/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50170/tests/dromaeo/cssquery-prototype.html) 14:50:39 INFO - PID 4208 | RSS: Main: 222736384 14:50:39 INFO - PID 4208 | 14:53:43 INFO - PID 4208 | Cycle 1(1): loaded http://localhost:50170/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50170/tests/dromaeo/cssquery-yui.html) 14:53:43 INFO - PID 4208 | RSS: Main: 188223488 14:53:43 INFO - PID 4208 | 14:56:46 INFO - PID 4208 | Cycle 1(1): loaded http://localhost:50170/tests/dromaeo/cssquery-yui.html (next: http://localhost:50170/tests/dromaeo/cssquery-dojo.html) 14:56:47 INFO - PID 4208 | RSS: Main: 270942208 14:56:47 INFO - PID 4208 | 14:56:47 INFO - PID 4208 | __start_tp_report 14:56:47 INFO - PID 4208 | _x_x_mozilla_page_load 14:56:47 INFO - PID 4208 | _x_x_mozilla_page_load_details 14:56:47 INFO - PID 4208 | |i|pagename|runs| 14:56:47 INFO - PID 4208 | |0;dojo.html;2668.786497080234;2718.8096833221684;2742.698303186649;2753.5594304911206;2755.683096443908;5093.821716239932;5144.485551444863;5236.371635403752;5246.5278124968745;5269.077911365505;5129.589632829374;5158;5204.713740744258;5211.218317252405;5239.083160446926;4703.835365762199;4705.364775755272;4711.976440117812;4740.620750339965;4772.188727916246;4777.140114779339;4809.302651115569;4815.133276010317;4823.855284341475;4846.927296090544;4614.077184563101;4650.790714417859;4651.627869770444;4693.6949098308505;4739.644526660514;4726.125666751662;4731.290306454025;4736;4782.497837727024;4797.592204662608;4658.324542941288;4676.766161691902;4697.906041879178;4717.9292310615365;4741.691790033637;5057.5701065409485;5103.56619687327;5196.558292545138;5198.662086964373;5228.137357336073;5498.477644623755;5517.117261238222;5557.916631250535;5564.137558678412;5567.749451274703;5641.830745077613;5651.2370829937845;5658.292713410824;5663.037283661787;5664.40523745005;5230.895382092375;5244.816431424882;5247.2653828464045;5251.52736253739;5300.655457395257;5576.553875689935;5639.210510528529;5639.605227634108;5717.028105222123;5753.798617048383;5491.478309560585;5491.890162196733;5495.697736624444;5499.670019798745;5545.889082218333;5551.666899986014;5558.722063896789;5619.409961953936;5620.971895140498;5622.212890195376;5401.810936617199;5434.673919564839;5435.7825686972865;5569.916451253234;5627.380988091307;5489.560835133179;5546.972265138729;5548.140038294071;5549.944500555023;5556.166575013699;4710.764461776966;4716.952830471651;4717.8112875485285;4725.054989002213;4768.165571025057;4028.67770578353;4033.173199494155;4039.939400908989;4048.8785336439955;4089.0186355275114;4655.045715628288;4673.719576825401;4723.197056500335;4765.189917713997;4804.966932109543;5573.359063707726;5612.326520817446;5616.747246373841;5635.520980716685;5670.659760414388;4784.976075119672;4795.065658575352;4795.832145874878;4798.832040878552;4841.878953026203;4910.410750709938;4943.060818444484;4950.430700469492;5008.774605142778;5013.1227035268685;5118.820841270538;5131.153359695609;5153.7680804364645;5156.40701319338;5177.792888284426;5494.313210848644;5606;5641.12562552805;5646.802361917313;5655.830325090254;5252.5535329497425;5267.209918512254;5270.31485906845;5271.920921186262;5275.762590683506;5491.68078110983;5527.806526771544;5537.667739935617;5540.556755459473;5602.747876345656;5443.591730620298;5513.365962914316;5549.056660367666;5555.944440555704;5591.692456914909;5427.348718153847;5433.1035379161995;5440.972795136157;5441.564674825925;5459.153831156177;5417.079096553674;5419.376771671308;5419.376771671308;5422.864428389322;5430.592705547178;5208.661437006583;5265.21021846726;5291.735413229246;5312.415634280149;5337.572994160536;5455.536279416294;5459.808906688247;5460.481254280837;5504.256925315191;5508.972455137859;5263.921041184309;5275.024120537712;5282.709450980233;5287.251087205182;5340.5994550408095 14:56:47 INFO - PID 4208 | |1;ext.html;610.7465401858229;648.079726787961;666.7533012785269;1070.0155856611916;1091.1052936591998;25973.220803375905;26561.070362537313;26612.334691632695;26620;26637.866810665968;31046.37907241854;31840.84079579599;31858;31859.840700796463;31880.840595797046;7185.173705023937;7348.816279592999;7353.77938661841;7355.301246381585;7357.742479013235;7163.785086447415;7297.270272972713;7417.629118544078;7425.071866016748;7426.665800039012;5482.2873026506495;5671.971640141794;7247.963760181218;7425.962870185642;7427.888581671279;7407.222241664628;7477.401807855358;7491.925080749177;7589.8861517077285;7622.656980435893;7388.44586656003;7489.775306740807;7494.962525187339;7495.437842161805;7496.325330720261;22902;23540.529189416116;28928.094707214466;29510;29528;2661.334666333417;2663.3607934095676;2666.7999900007385;2668.9332766680795;2714.7013828478853;22122.52198539279;22763.317100487013;22785.20251791196;23013.424664383358;23281.53436931269;2972.18264977133;3000.534917087855;3005.2186431527743;3026.7124623160767;3047.024952015356;150938;151738.24130879386;151801.24099379542;151865;152075;43228.78385608115;43244.56755432405;43318.783406082766;43328.56671433245;43343;45225.547744522795;45430.318545221846;45494;45634.771826140655;45785.7710711451;13267;13515.662108447368;13553.796693049611;13594.93202533981;13621.114627549177;11000.834987475195;11006.669799906016;11010.339379637247;11013.559457621766;11019.338839669485;10229.641962531276;12061.939690301493;12507.812382814265;12611.495540178474;12708.809367859489;4403.713758605744;4486.730796152241;4550.544945505423;4587.61005314552;4808.27875818623;854.918782715641;874.1302404107827;877.6313948141795;889.4129874282942;890.2121622364255;18545.35091271799;19309.51726206856;19789.901050494656;19827.30604428838;19904.50238744043;936.8594710793299;946.2382781860669;951.1962391778935;953.5613617735919;957.593022965237;10621.362718236931;10840.349579025284;10851.78296434067;10858.619948301772;10972.945135274433;199461;200129;200130;200156;200200;41785.79107104445;41847.815218478514;41920.580794192276;41936.16127677429;41972;1193.8519603704576;1228.8033914573625;1230.280286032663;1234.6357824441811;1236.8824961628811;19255.32606358771;19434.319798806973;19513.70729439088;20756.584868302853;20882.68675969892;30573;30677.386452270384;30707.692923071376;30729.385412292075;30753;216577;216848;217135;217348;217433;27143.321416964733;28214.43571128607;28229.153125406272;28239.858800705864;28779.424411511234;34725.30549389048;34820.129496762784;36419.63580364163;36785.08037299089;37561.624383755814;27590.310242244104;27755.444891101663;27867.860660697377;28039.299017524725;28759.568606471337;27502.724972750017;27743.722562774114;28014;28117.43765124727;28545.571816422354;30504.694953051072;30663.84668076645;30782.692173077983;31287;31506;26611.201663950113;26799.86600066987;26884.193474195807;27048.1885543434;27491.31271718223;20897.477563061046;21872.12511499586;22029.449263768533;22043.33869983903;22279.331620051424 14:56:47 INFO - PID 4208 | |2;jquery.html;381.91216020315346;419.54479389862007;428.7513242319455;440.3988555621577;444.9154660614483;12854.421551030202;17527.737083943735;17624.118794060294;18302.267909283644;18321.908390458062;18764.71852922207;18805.871647701148;18946.905265473688;19157.329493467732;19248.711269330935;5659.151127330892;5869.941300587004;5874.765009399619;5876.44173803488;5879.617824841393;3438.673326034023;3602.5676918769786;5472.151816468454;5663.2637757091725;5664.461876121783;5657.575681823859;5665.433456654322;5665.716714164296;5674.517665998395;5682;5471.069918113911;5637.23897273871;5696.344920334173;5704.857378565527;5752.079667253241;5102.617303702237;5128.9230661540105;5136.203888397305;5138.306328645623;5197.116490196638;22275.220367287227;22879.7712022881;22904.885475572682;22999.425014374607;23157.0737170513;1548.992255038729;1578.2897696036714;1586.6430053238043;1597.5846279967177;1624.3827345608593;13855.584332470042;13934.58196254114;14081.64795880101;14398.568042958728;14620.488282910048;1729.4292883348592;1768.151287382051;1785.4286628278956;1800.5244919913323;1806.4309742431092;84695.57652211761;86998.56500717456;88236;89640.55179724125;90768;9513.14381705636;10627.202959778047;10770.78458430827;10803.891961080288;10971.83542246867;13578.321083945762;13966.86033139676;14436.566902992927;14438.422463101568;14503.1298122113;7239.565626062348;7403.370713489414;7422;7498.100227972678;7519.586422746692;6788.9660551696925;6794.082798822095;6963;6975.267596902354;6989.930100698928;6853.383195512328;6883.621400822903;6924.134483189601;6933.514653974174;6964.477664175207;2031.8476114291486;2033.4814622271226;2034.4710375302232;2040.0717673458541;2194.9451263718533;828.1842385250524;865.5023361567124;866.2247288676599;870.3907264914585;878.3236907581254;16299.674006519803;16307.592310192103;16596;17089;17249.310027598756;845.6617353058703;878.3895192841042;884.3765145572356;884.8539990901558;886.8270687215932;8653.913460865437;8737.956310218407;9219.769505762275;9384.765380865396;9504.382215155993;317772;322535;323177;456098;463566;75920;78645;84794;94298.71212915667;96525;1099.1756182862853;1143.1197977557401;1148.121686909515;1152.8904754048351;1162.8604567451791;16584.83415165881;16712.749308760136;17274.309027638752;17634.118294084987;18015.72976405329;35067.298654026265;41104.58895411008;41200.793996029824;41299.38050929298;42029.579704202566;52632.73683631558;54313;55194;55281.44718552763;55963.720181398836;7319.34125928669;7548.773536793905;7654.119776225805;7811.101723301893;8033.799155020937;9122.27021838238;10659.14726821868;10688.57245710194;10988.340699558052;11795.233309834834;9652.27607929422;9807.215422766305;10418.843717344398;10787.08309793645;10841.566337346734;8570.52862092566;9621.422714637145;10054.748631284276;10177.440240786827;10232.948835255776;10402;10776.730581735519;10820.783584328112;10906.890931090587;11067.280626759235;10969.341839489656;11184.832227516432;11256.380899050631;11286;11396.373199474108;10722.839157412802;10759.462026898467;10826.13390928708;11286.266392684449;11298 14:56:47 INFO - PID 4208 | |3;mootools.html;1276.5978716704237;1326.1910234756797;1344.6167842164973;1346.3066520741813;1350.3585796746543;9479.09948554887;9883.061109194632;9885.703428897135;9897.15874150698;10200.285979981405;9729.805403891918;9936.155426788713;9957.302988790787;10044.296899217057;10057.094861462467;2215.523662412581;2247.5954328220914;2249.5950728868797;2252.876091814958;2259.58197733419;1770.283035370677;2114.651082571374;2206.3160420269687;2246.2475070851283;2251.3696165073725;1874.9250029998784;2044.836413086957;2141.1114387529155;2143.9356819295367;2144.281665642012;2193.670949357593;2237.0827960536185;2278.464560828204;2280.84034117611;2282.3495303838326;2265.943351416228;2302.3553405046614;2302.5740238056014;2303.7120359955006;2307.3885420363617;9760.609575616967;9837;10099.29304948654;10121.139703125244;10131.64539241123;5503.532199763025;5746;5747.0230060889335;5748.971255143698;5756.395578464285;3061.3571150058515;3093.3194697166587;3269.820159891205;3288.8684452621865;3303.8182899940493;1031.4481752262504;1048.7483004078965;1069.9251052426334;1070.5664205996584;1074.0441007503327;14512.27438628075;14974;15099.924500377538;15117.09297442157;15141.924290378587;4893.192623217201;4952.950470495321;5013.774380152902;5016.824411145592;5125.026245013435;12084.697882553006;12097.334646594521;12128.69678258033;12133.332666703416;12141;5640.097584386478;5646.20953066571;5648.406917273709;5656.179853921159;5663.037283661787;4973.90052198954;5065.341505604248;5105.8723531911255;5132.897342053138;5138.511841375063;4056.698138524947;4145.0880806222585;4152.534376846733;4189.937150942738;4205.600467955539;1244.9315287659265;1246.2335663566842;1269.6825793551611;1293.6636474516506;1297.9351032448528;563.1890078287335;647.4011539326199;721.5923003503004;726.8982342472058;742.9962850185715;5577.776888924479;5821.621594596338;6250.937490625036;6295.307516173309;6301.6219026858535;838.7316058861228;843.6667516331015;846.5386364431399;857.1171693156059;867.700643278068;3611.7110631149444;3654.2691461707764;3668.908722028418;3736.663700266989;3942.7240093193773;12604.873951260553;13004.284764337863;13142.934285328512;13224.603261902159;13243.139195952232;12953.740925181444;13079.41142648583;13098;13101.082924195214;13126.081174317706;8855.557222138867;8887.644494220158;8995.77510562228;9016.323775716848;9019.684311049083;5560.972195138998;5561.5272701820795;5589.972050139723;5706.058500347396;5813.534917206698;11722.706932326419;11725.648230553097;11740.647780566596;11845.46009018822;11873.406329683308;12235.571754988532;12237.143399961917;12249.020078393529;12258.938705306417;12289;7793.4154938378715;7805.375569954504;7842.176571459914;7945.761627151194;7958.761237162895;8834.779130521789;9246.95376523113;9336.673216437392;9353.11145441182;9353.485558294358;7495.400367970658;7498.175200728025;7509.4367922404945;7535.472516923763;7543.094828620381;7248.818779530343;7271.818204544717;7501.062367204099;7619.161892191743;7657.1959944205055;7093.893591596027;7112.466565007541;7177.641117943977;7209.567425954249;7209.855802884018;5163.3545806774155;5172.870678233075;5220.477952204749;5331.09371406854;5409.242706021081;5151.330327057459;5201.453847345974;5235.65968212065;5304.920426193533;5400.216968539618 14:56:47 INFO - PID 4208 | |4;prototype.html;372.5027088836403;390.46896221139247;391.907901643114;393.65555139253155;396.2214249000714;5374.408815030349;5435.673859568428;5444.631585260409;5466.617336786427;5469.617126801126;5118.436971933093;5282.815101471439;5314.601404894629;5326.2543243945875;5369.194620806871;4138.97930510347;4143.585641435855;4143.792810359485;4143.97928010361;4149.211649786546;4132.710710250284;4147.232761939036;4154.106867023589;4163.20899029185;4176.352665336863;3943.940840887389;4087;4100.5489396166395;4134.607212314825;4136.358864376011;4076.3885417187657;4084.14233011068;4085.836566537335;4089.3048181809163;4100.7334523255895;3571.3035957988204;3573.5711714594413;3575.6781889629797;3578.2127931854952;3591.9281614367565;4934.358533390672;5173.9223911640975;5184.196449550326;5212.817551385683;5224.294720212761;4690.249560070372;4709.788059537294;4711.128441238382;4724.834630787906;4726.810927562893;5575.944240557583;5578.693171875545;5605.355384130837;5624.971875140639;5647.322321321427;3844.1158533537164;3959.3467077932105;3973.5827738087664;3975.5626881043063;3977.1449138435373;5550.805721799758;5755.280589926259;5768.509676677488;5785.27684039495;5874.32445268791;3993.900152496211;3998.520177578709;3999.140184860211;4004.939925901114;4005.859794907164;4041.515018197835;4042.211768705074;4050.5544390117066;4053.918921621551;4078.8368465261055;4423.292273236267;4461.386277115546;4513.571210734975;4682.672212945061;4690.343351930733;5603.467670571289;5620.803271885465;5621.915671264935;5632.802851900164;5646.661200327993;1400.7408629403592;1422;1422.8150340455675;1423.843377228504;1425.7504936636046;912.5665308978182;953.2040745977187;964.3346091197192;971.6793458158791;975.7609385700549;4903.4606193318705;4940.925886111711;4942.036302920896;4948.356713627206;4951.455339912607;5807.622504537191;5810.1575271586225;5826.242588463472;5826.650400975955;5832.650040997469;4746.454157771831;4863.586595139453;4914.778834952435;4916.532929371704;4930.334404855299;3038.7872848900806;3067.831269280211;3067.8466076696077;3069.4014667139695;3073.1548824073557;5378.91931621026;5401.648892821954;5405.270288511001;5406.567474602022;5439.945600543944;5653.293338332708;5818.796342128006;5896;5908.379620139909;5916.171735956969;5372.57019438444;5377.973110134502;5383.5423988961375;5391.056565101092;5408.161734931092;5566.248556444828;5753.770978331007;5757.798477053283;5759.65442073477;5818.4763371296785;5647.124695672177;5666.490015898589;5676.978143933967;5688.829335119953;5696.715164241688;5114.851762259786;5279.39286982002;5303.257543943928;5303.602229832701;5361.329833770778;3770.8114594269628;3888.3389823729467;3892.9221415572097;3986.880393588197;4036.7779772112817;5795.7391917362975;5800.738966746422;5819.621724587888;5825;5832.504237139721;5498.725063746717;5605.355384130877;5643.52030077448;5647.943520564906;5651.6043876928225;5605.775768969195;5608.9158662619275;5621.7189140542;5627.114916502854;5634.774609015593;5304.469553044664;5508.338999319947;5526.50261476469;5536.806211782568;5546.889062218653;5632;5640.802571909963;5653.293338332708;5663.801766938137;5673.205751194919;5423.566114710892;5432.809851655173;5443.7822487100075;5473.808416705396;5476.424975377528 14:56:47 INFO - PID 4208 | |5;yui.html;682.4892062848353;700.1912790726713;704.718112754898;706.0926709178707;707.377507793142;12687.238765674054;13141.67145821355;13151.605451836436;13160.34198290084;13165.736685266302;12415.751684966295;12581.678923713016;14073.929630351833;14569.271536423163;14773.113613183217;814.3322475570035;831.5883637599381;839.1189251286156;851.5061264466594;854.8888644476209;838.932885369169;839.5382539603224;840.4873027453249;840.604915689628;842.5281842168405;876.4084243135858;878.8549889268295;884.1512148337604;884.5311984648115;884.9601767920427;870.5995242188601;870.8214815962775;870.9390342676015;871.5206636350014;876.9605367758467;651.0982289528989;652.54974067893;652.624740774052;653.0204692960559;653.0269897852213;13368.732625347438;13382.397792099378;13430.798538021938;13507;14355.928220358936;13407.597772066854;14266.215358155298;14292.714145717131;14375.712485750331;14395;10641.840372394343;10658.946705266502;10669.413182274971;10876.673699789018;10940.781184376348;2741.739534744196;2747.615333853262;2752.4357506711076;2799.4261176458776;2857.6856545780156;64875.67562162159;65851.341486585;65938.3406165937;66005.66997164984;66453.66773166103;10619.787604248026;10635.627753028606;10734.355938643706;10761;10824.78350432987;20994.160233590486;21054.684179737316;21206.787932120482;21319.467013324793;21411.67882481764;6445.129907462527;6474.384933431316;6493.123428337209;6547.312532184147;6649.301823308483;3229.6770322967513;3296.8516416761304;3333.4833100869405;3352.2122301259183;3392.9321413571593;3449.2756521130846;3461.3250416169108;3509.964900350964;3518.3315170117594;3527.417976033977;628.2868943748878;631.5831551176293;635.5138319185827;641.5060403489381;648.2383199740286;496.4340651657114;517.2913109040638;522.947705229474;554.6810583914265;554.7780887644893;7963.681452741941;8316.022745864142;8585.441946273471;8616.870746938801;8678.435901666371;530.3556179242162;531.5614617940215;538.4611556511069;541.507228422134;564.7966731976485;3713.8514459421863;4086.570910054461;4163;4397.670174736908;4441.400410944546;64130.70104834866;64432;64652.67673661601;65885.67057164683;66141.33858661448;33155;40076.398854017214;44336;44417.33373999393;45653;40671;46691.83270418267;46738;47736.52263477321;47850.60746962638;8447.662093516436;8977.371583989057;9015.233705135137;9056.501892395981;9211.815763684555;23810.523789524457;24210.15264465735;24497;24840.751592483844;24867.62698559559;29947.850260748557;30207.848960755055;30335.696643033287;30541.694583054774;30920.845395772878;10778.13774898022;10852.511636976196;10920.071793897609;11058.502367393647;11149.275297105662;11537.826932596186;11834.171607987359;12126.575569855013;12319.50721971147;12550.811737823757;10607.4165920872;10886.346819190874;11025.944870275918;11325.660230193107;11414.94292528532;11504.94247528757;11580.189386742848;11639.59261425846;11805.23265987708;12082.275063496218;10925.617603383844;11256.21206515536;11475.655730328104;11475.655730328104;11684;7411.62941852916;7532.359749421361;7819.765407037798;7896.973393458814;8047;8027.678892844221;8167.305779008613;8203.917960820316;8219.558384643547;8375.958120209603 14:56:47 INFO - PID 4208 | __end_tp_report 14:56:47 INFO - PID 4208 | __start_cc_report 14:56:47 INFO - PID 4208 | _x_x_mozilla_cycle_collect,1187 14:56:47 INFO - PID 4208 | __end_cc_report 14:56:47 INFO - PID 4208 | __startTimestamp1490392607204__endTimestamp 14:56:47 INFO - PID 4208 | 14:56:47 INFO - PID 4208 | ------- Summary: start ------- 14:56:47 INFO - PID 4208 | Number of tests: 6 14:56:47 INFO - PID 4208 | 14:56:47 INFO - PID 4208 | [#0] dojo.html Cycles:165 Average:5123.72 Median:5267.21 stddev:574.68 (10.9%) stddev-sans-first:543.22 14:56:47 INFO - PID 4208 | Values: 2668.8 2718.8 2742.7 2753.6 2755.7 5093.8 5144.5 5236.4 5246.5 5269.1 5129.6 5158.0 5204.7 5211.2 5239.1 4703.8 4705.4 4712.0 4740.6 4772.2 4777.1 4809.3 4815.1 4823.9 4846.9 4614.1 4650.8 4651.6 4693.7 4739.6 4726.1 4731.3 4736.0 4782.5 4797.6 4658.3 4676.8 4697.9 4717.9 4741.7 5057.6 5103.6 5196.6 5198.7 5228.1 5498.5 5517.1 5557.9 5564.1 5567.7 5641.8 5651.2 5658.3 5663.0 5664.4 5230.9 5244.8 5247.3 5251.5 5300.7 5576.6 5639.2 5639.6 5717.0 5753.8 5491.5 5491.9 5495.7 5499.7 5545.9 5551.7 5558.7 5619.4 5621.0 5622.2 5401.8 5434.7 5435.8 5569.9 5627.4 5489.6 5547.0 5548.1 5549.9 5556.2 4710.8 4717.0 4717.8 4725.1 4768.2 4028.7 4033.2 4039.9 4048.9 4089.0 4655.0 4673.7 4723.2 4765.2 4805.0 5573.4 5612.3 5616.7 5635.5 5670.7 4785.0 4795.1 4795.8 4798.8 4841.9 4910.4 4943.1 4950.4 5008.8 5013.1 5118.8 5131.2 5153.8 5156.4 5177.8 5494.3 5606.0 5641.1 5646.8 5655.8 5252.6 5267.2 5270.3 5271.9 5275.8 5491.7 5527.8 5537.7 5540.6 5602.7 5443.6 5513.4 5549.1 5555.9 5591.7 5427.3 5433.1 5441.0 5441.6 5459.2 5417.1 5419.4 5419.4 5422.9 5430.6 5208.7 5265.2 5291.7 5312.4 5337.6 5455.5 5459.8 5460.5 5504.3 5509.0 5263.9 5275.0 5282.7 5287.3 5340.6 14:56:47 INFO - PID 4208 | 14:56:47 INFO - PID 4208 | [#1] ext.html Cycles:180 Average:32643.48 Median:28392.72 stddev:49816.66 (175.5%) stddev-sans-first:49898.35 14:56:47 INFO - PID 4208 | Values: 610.7 648.1 666.8 1070.0 1091.1 25973.2 26561.1 26612.3 26620.0 26637.9 31046.4 31840.8 31858.0 31859.8 31880.8 7185.2 7348.8 7353.8 7355.3 7357.7 7163.8 7297.3 7417.6 7425.1 7426.7 5482.3 5672.0 7248.0 7426.0 7427.9 7407.2 7477.4 7491.9 7589.9 7622.7 7388.4 7489.8 7495.0 7495.4 7496.3 22902.0 23540.5 28928.1 29510.0 29528.0 2661.3 2663.4 2666.8 2668.9 2714.7 22122.5 22763.3 22785.2 23013.4 23281.5 2972.2 3000.5 3005.2 3026.7 3047.0 150938.0 151738.2 151801.2 151865.0 152075.0 43228.8 43244.6 43318.8 43328.6 43343.0 45225.5 45430.3 45494.0 45634.8 45785.8 13267.0 13515.7 13553.8 13594.9 13621.1 11000.8 11006.7 11010.3 11013.6 11019.3 10229.6 12061.9 12507.8 12611.5 12708.8 4403.7 4486.7 4550.5 4587.6 4808.3 854.9 874.1 877.6 889.4 890.2 18545.4 19309.5 19789.9 19827.3 19904.5 936.9 946.2 951.2 953.6 957.6 10621.4 10840.3 10851.8 10858.6 10972.9 199461.0 200129.0 200130.0 200156.0 200200.0 41785.8 41847.8 41920.6 41936.2 41972.0 1193.9 1228.8 1230.3 1234.6 1236.9 19255.3 19434.3 19513.7 20756.6 20882.7 30573.0 30677.4 30707.7 30729.4 30753.0 216577.0 216848.0 217135.0 217348.0 217433.0 27143.3 28214.4 28229.2 28239.9 28779.4 34725.3 34820.1 36419.6 36785.1 37561.6 27590.3 27755.4 27867.9 28039.3 28759.6 27502.7 27743.7 28014.0 28117.4 28545.6 30504.7 30663.8 30782.7 31287.0 31506.0 26611.2 26799.9 26884.2 27048.2 27491.3 20897.5 21872.1 22029.4 22043.3 22279.3 14:56:47 INFO - PID 4208 | 14:56:47 INFO - PID 4208 | [#2] jquery.html Cycles:180 Average:25406.50 Median:20743.25 stddev:63963.85 (308.4%) stddev-sans-first:64115.68 14:56:47 INFO - PID 4208 | Values: 381.9 419.5 428.8 440.4 444.9 12854.4 17527.7 17624.1 18302.3 18321.9 18764.7 18805.9 18946.9 19157.3 19248.7 5659.2 5869.9 5874.8 5876.4 5879.6 3438.7 3602.6 5472.2 5663.3 5664.5 5657.6 5665.4 5665.7 5674.5 5682.0 5471.1 5637.2 5696.3 5704.9 5752.1 5102.6 5128.9 5136.2 5138.3 5197.1 22275.2 22879.8 22904.9 22999.4 23157.1 1549.0 1578.3 1586.6 1597.6 1624.4 13855.6 13934.6 14081.6 14398.6 14620.5 1729.4 1768.2 1785.4 1800.5 1806.4 84695.6 86998.6 88236.0 89640.6 90768.0 9513.1 10627.2 10770.8 10803.9 10971.8 13578.3 13966.9 14436.6 14438.4 14503.1 7239.6 7403.4 7422.0 7498.1 7519.6 6789.0 6794.1 6963.0 6975.3 6989.9 6853.4 6883.6 6924.1 6933.5 6964.5 2031.8 2033.5 2034.5 2040.1 2194.9 828.2 865.5 866.2 870.4 878.3 16299.7 16307.6 16596.0 17089.0 17249.3 845.7 878.4 884.4 884.9 886.8 8653.9 8738.0 9219.8 9384.8 9504.4 317772.0 322535.0 323177.0 456098.0 463566.0 75920.0 78645.0 84794.0 94298.7 96525.0 1099.2 1143.1 1148.1 1152.9 1162.9 16584.8 16712.7 17274.3 17634.1 18015.7 35067.3 41104.6 41200.8 41299.4 42029.6 52632.7 54313.0 55194.0 55281.4 55963.7 7319.3 7548.8 7654.1 7811.1 8033.8 9122.3 10659.1 10688.6 10988.3 11795.2 9652.3 9807.2 10418.8 10787.1 10841.6 8570.5 9621.4 10054.7 10177.4 10232.9 10402.0 10776.7 10820.8 10906.9 11067.3 10969.3 11184.8 11256.4 11286.0 11396.4 10722.8 10759.5 10826.1 11286.3 11298.0 14:56:47 INFO - PID 4208 | 14:56:47 INFO - PID 4208 | [#3] mootools.html Cycles:180 Average:6281.25 Median:4549.40 stddev:4018.19 (88.3%) stddev-sans-first:4011.86 14:56:47 INFO - PID 4208 | Values: 1276.6 1326.2 1344.6 1346.3 1350.4 9479.1 9883.1 9885.7 9897.2 10200.3 9729.8 9936.2 9957.3 10044.3 10057.1 2215.5 2247.6 2249.6 2252.9 2259.6 1770.3 2114.7 2206.3 2246.2 2251.4 1874.9 2044.8 2141.1 2143.9 2144.3 2193.7 2237.1 2278.5 2280.8 2282.3 2265.9 2302.4 2302.6 2303.7 2307.4 9760.6 9837.0 10099.3 10121.1 10131.6 5503.5 5746.0 5747.0 5749.0 5756.4 3061.4 3093.3 3269.8 3288.9 3303.8 1031.4 1048.7 1069.9 1070.6 1074.0 14512.3 14974.0 15099.9 15117.1 15141.9 4893.2 4953.0 5013.8 5016.8 5125.0 12084.7 12097.3 12128.7 12133.3 12141.0 5640.1 5646.2 5648.4 5656.2 5663.0 4973.9 5065.3 5105.9 5132.9 5138.5 4056.7 4145.1 4152.5 4189.9 4205.6 1244.9 1246.2 1269.7 1293.7 1297.9 563.2 647.4 721.6 726.9 743.0 5577.8 5821.6 6250.9 6295.3 6301.6 838.7 843.7 846.5 857.1 867.7 3611.7 3654.3 3668.9 3736.7 3942.7 12604.9 13004.3 13142.9 13224.6 13243.1 12953.7 13079.4 13098.0 13101.1 13126.1 8855.6 8887.6 8995.8 9016.3 9019.7 5561.0 5561.5 5590.0 5706.1 5813.5 11722.7 11725.6 11740.6 11845.5 11873.4 12235.6 12237.1 12249.0 12258.9 12289.0 7793.4 7805.4 7842.2 7945.8 7958.8 8834.8 9247.0 9336.7 9353.1 9353.5 7495.4 7498.2 7509.4 7535.5 7543.1 7248.8 7271.8 7501.1 7619.2 7657.2 7093.9 7112.5 7177.6 7209.6 7209.9 5163.4 5172.9 5220.5 5331.1 5409.2 5151.3 5201.5 5235.7 5304.9 5400.2 14:56:47 INFO - PID 4208 | 14:56:47 INFO - PID 4208 | [#4] prototype.html Cycles:180 Average:4624.22 Median:5304.04 stddev:1352.57 (25.5%) stddev-sans-first:1318.19 14:56:47 INFO - PID 4208 | Values: 372.5 390.5 391.9 393.7 396.2 5374.4 5435.7 5444.6 5466.6 5469.6 5118.4 5282.8 5314.6 5326.3 5369.2 4139.0 4143.6 4143.8 4144.0 4149.2 4132.7 4147.2 4154.1 4163.2 4176.4 3943.9 4087.0 4100.5 4134.6 4136.4 4076.4 4084.1 4085.8 4089.3 4100.7 3571.3 3573.6 3575.7 3578.2 3591.9 4934.4 5173.9 5184.2 5212.8 5224.3 4690.2 4709.8 4711.1 4724.8 4726.8 5575.9 5578.7 5605.4 5625.0 5647.3 3844.1 3959.3 3973.6 3975.6 3977.1 5550.8 5755.3 5768.5 5785.3 5874.3 3993.9 3998.5 3999.1 4004.9 4005.9 4041.5 4042.2 4050.6 4053.9 4078.8 4423.3 4461.4 4513.6 4682.7 4690.3 5603.5 5620.8 5621.9 5632.8 5646.7 1400.7 1422.0 1422.8 1423.8 1425.8 912.6 953.2 964.3 971.7 975.8 4903.5 4940.9 4942.0 4948.4 4951.5 5807.6 5810.2 5826.2 5826.7 5832.7 4746.5 4863.6 4914.8 4916.5 4930.3 3038.8 3067.8 3067.8 3069.4 3073.2 5378.9 5401.6 5405.3 5406.6 5439.9 5653.3 5818.8 5896.0 5908.4 5916.2 5372.6 5378.0 5383.5 5391.1 5408.2 5566.2 5753.8 5757.8 5759.7 5818.5 5647.1 5666.5 5677.0 5688.8 5696.7 5114.9 5279.4 5303.3 5303.6 5361.3 3770.8 3888.3 3892.9 3986.9 4036.8 5795.7 5800.7 5819.6 5825.0 5832.5 5498.7 5605.4 5643.5 5647.9 5651.6 5605.8 5608.9 5621.7 5627.1 5634.8 5304.5 5508.3 5526.5 5536.8 5546.9 5632.0 5640.8 5653.3 5663.8 5673.2 5423.6 5432.8 5443.8 5473.8 5476.4 14:56:47 INFO - PID 4208 | 14:56:47 INFO - PID 4208 | [#5] yui.html Cycles:180 Average:13544.22 Median:4280.34 stddev:16544.22 (386.5%) stddev-sans-first:16562.44 14:56:47 INFO - PID 4208 | Values: 682.5 700.2 704.7 706.1 707.4 12687.2 13141.7 13151.6 13160.3 13165.7 12415.8 12581.7 14073.9 14569.3 14773.1 814.3 831.6 839.1 851.5 854.9 838.9 839.5 840.5 840.6 842.5 876.4 878.9 884.2 884.5 885.0 870.6 870.8 870.9 871.5 877.0 651.1 652.5 652.6 653.0 653.0 13368.7 13382.4 13430.8 13507.0 14355.9 13407.6 14266.2 14292.7 14375.7 14395.0 10641.8 10658.9 10669.4 10876.7 10940.8 2741.7 2747.6 2752.4 2799.4 2857.7 64875.7 65851.3 65938.3 66005.7 66453.7 10619.8 10635.6 10734.4 10761.0 10824.8 20994.2 21054.7 21206.8 21319.5 21411.7 6445.1 6474.4 6493.1 6547.3 6649.3 3229.7 3296.9 3333.5 3352.2 3392.9 3449.3 3461.3 3510.0 3518.3 3527.4 628.3 631.6 635.5 641.5 648.2 496.4 517.3 522.9 554.7 554.8 7963.7 8316.0 8585.4 8616.9 8678.4 530.4 531.6 538.5 541.5 564.8 3713.9 4086.6 4163.0 4397.7 4441.4 64130.7 64432.0 64652.7 65885.7 66141.3 33155.0 40076.4 44336.0 44417.3 45653.0 40671.0 46691.8 46738.0 47736.5 47850.6 8447.7 8977.4 9015.2 9056.5 9211.8 23810.5 24210.2 24497.0 24840.8 24867.6 29947.9 30207.8 30335.7 30541.7 30920.8 10778.1 10852.5 10920.1 11058.5 11149.3 11537.8 11834.2 12126.6 12319.5 12550.8 10607.4 10886.3 11025.9 11325.7 11414.9 11504.9 11580.2 11639.6 11805.2 12082.3 10925.6 11256.2 11475.7 11475.7 11684.0 7411.6 7532.4 7819.8 7897.0 8047.0 8027.7 8167.3 8203.9 8219.6 8376.0 14:56:47 INFO - PID 4208 | -------- Summary: end -------- 14:56:47 INFO - PID 4208 | 14:56:48 INFO - TEST-INFO | 4208: exit 0 14:56:48 INFO - TEST-OK | dromaeo_css | took 1122817ms 14:56:48 INFO - TEST-START | kraken 14:56:48 INFO - operating with platform_type : linux_ 14:56:48 INFO - Initialising browser for kraken test... 14:56:48 INFO - TEST-INFO | started process 4526 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPYJVD3/profile http://localhost:50170/getInfo.html) 14:56:52 INFO - PID 4526 | __metrics Screen width/height:1600/1200 14:56:52 INFO - PID 4526 | colorDepth:24 14:56:52 INFO - PID 4526 | Browser inner width/height: 1024/697 14:56:52 INFO - PID 4526 | __metrics 14:56:53 INFO - PID 4526 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:56:53 INFO - PID 4526 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:56:54 INFO - TEST-INFO | 4526: exit 0 14:56:54 INFO - Browser initialized. 14:56:54 INFO - Running cycle 1/1 for kraken test... 14:56:54 INFO - TEST-INFO | started process 4620 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPYJVD3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:57:06 INFO - PID 4620 | RSS: Main: 150167552 14:57:06 INFO - PID 4620 | 14:57:08 INFO - PID 4620 | Cycle 1(1): loaded http://localhost:50170/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50170/tests/kraken/driver.html?testName=audio-beat-detection) 14:57:09 INFO - PID 4620 | RSS: Main: 205234176 14:57:09 INFO - PID 4620 | 14:57:12 INFO - PID 4620 | Cycle 1(1): loaded http://localhost:50170/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50170/tests/kraken/driver.html?testName=audio-dft) 14:57:13 INFO - PID 4620 | RSS: Main: 184512512 14:57:13 INFO - PID 4620 | 14:57:16 INFO - PID 4620 | Cycle 1(1): loaded http://localhost:50170/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50170/tests/kraken/driver.html?testName=audio-fft) 14:57:16 INFO - PID 4620 | RSS: Main: 267653120 14:57:16 INFO - PID 4620 | 14:57:19 INFO - PID 4620 | Cycle 1(1): loaded http://localhost:50170/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50170/tests/kraken/driver.html?testName=audio-oscillator) 14:57:19 INFO - PID 4620 | RSS: Main: 177078272 14:57:19 INFO - PID 4620 | 14:57:21 INFO - PID 4620 | Cycle 1(1): loaded http://localhost:50170/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50170/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:57:22 INFO - PID 4620 | RSS: Main: 181710848 14:57:22 INFO - PID 4620 | 14:57:25 INFO - PID 4620 | Cycle 1(1): loaded http://localhost:50170/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50170/tests/kraken/driver.html?testName=imaging-darkroom) 14:57:26 INFO - PID 4620 | RSS: Main: 219709440 14:57:26 INFO - PID 4620 | 14:57:29 INFO - PID 4620 | Cycle 1(1): loaded http://localhost:50170/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50170/tests/kraken/driver.html?testName=imaging-desaturate) 14:57:30 INFO - PID 4620 | RSS: Main: 215142400 14:57:30 INFO - PID 4620 | 14:57:33 INFO - PID 4620 | Cycle 1(1): loaded http://localhost:50170/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50170/tests/kraken/driver.html?testName=json-parse-financial) 14:57:33 INFO - PID 4620 | RSS: Main: 217116672 14:57:33 INFO - PID 4620 | 14:57:35 INFO - PID 4620 | Cycle 1(1): loaded http://localhost:50170/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50170/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:57:36 INFO - PID 4620 | RSS: Main: 181100544 14:57:36 INFO - PID 4620 | 14:57:38 INFO - PID 4620 | Cycle 1(1): loaded http://localhost:50170/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50170/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:57:38 INFO - PID 4620 | RSS: Main: 187703296 14:57:38 INFO - PID 4620 | 14:57:40 INFO - PID 4620 | Cycle 1(1): loaded http://localhost:50170/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50170/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:57:41 INFO - PID 4620 | RSS: Main: 197750784 14:57:41 INFO - PID 4620 | 14:57:44 INFO - PID 4620 | Cycle 1(1): loaded http://localhost:50170/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50170/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:57:45 INFO - PID 4620 | RSS: Main: 206266368 14:57:45 INFO - PID 4620 | 14:57:48 INFO - PID 4620 | Cycle 1(1): loaded http://localhost:50170/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50170/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:57:48 INFO - PID 4620 | RSS: Main: 202395648 14:57:48 INFO - PID 4620 | 14:57:50 INFO - PID 4620 | Cycle 1(1): loaded http://localhost:50170/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50170/tests/kraken/driver.html?testName=ai-astar) 14:57:51 INFO - PID 4620 | RSS: Main: 271978496 14:57:51 INFO - PID 4620 | 14:57:51 INFO - PID 4620 | __start_tp_report 14:57:51 INFO - PID 4620 | _x_x_mozilla_page_load 14:57:51 INFO - PID 4620 | _x_x_mozilla_page_load_details 14:57:51 INFO - PID 4620 | |i|pagename|runs| 14:57:51 INFO - PID 4620 | |0;ai-astar;103;111;112;111;111;111;112;115;115;111 14:57:51 INFO - PID 4620 | |1;audio-beat-detection;162;123;124;162;124;123;122;124;146;124 14:57:51 INFO - PID 4620 | |2;audio-dft;161;162;163;163;164;161;162;163;161;162 14:57:51 INFO - PID 4620 | |3;audio-fft;74;74;76;109;75;75;76;76;89;74 14:57:51 INFO - PID 4620 | |4;audio-oscillator;84;83;119;85;83;83;102;85;83;111 14:57:51 INFO - PID 4620 | |5;imaging-gaussian-blur;113;113;113;113;113;113;113;113;113;113 14:57:51 INFO - PID 4620 | |6;imaging-darkroom;149;148;149;150;149;149;149;148;148;148 14:57:51 INFO - PID 4620 | |7;imaging-desaturate;94;93;93;94;94;95;93;94;93;93 14:57:51 INFO - PID 4620 | |8;json-parse-financial;64;64;62;64;64;66;66;65;65;65 14:57:51 INFO - PID 4620 | |9;json-stringify-tinderbox;56;55;55;57;56;55;55;55;55;56 14:57:51 INFO - PID 4620 | |10;stanford-crypto-aes;84;83;83;84;82;82;82;83;84;83 14:57:51 INFO - PID 4620 | |11;stanford-crypto-ccm;139;139;132;137;138;142;140;146;178;133 14:57:51 INFO - PID 4620 | |12;stanford-crypto-pbkdf2;185;195;194;196;196;207;188;190;195;199 14:57:51 INFO - PID 4620 | |13;stanford-crypto-sha256-iterative;75;70;73;74;71;71;71;71;70;70 14:57:51 INFO - PID 4620 | __end_tp_report 14:57:51 INFO - PID 4620 | __start_cc_report 14:57:51 INFO - PID 4620 | _x_x_mozilla_cycle_collect,1986 14:57:51 INFO - PID 4620 | __end_cc_report 14:57:51 INFO - PID 4620 | __startTimestamp1490392671009__endTimestamp 14:57:51 INFO - PID 4620 | 14:57:51 INFO - PID 4620 | ------- Summary: start ------- 14:57:51 INFO - PID 4620 | Number of tests: 14 14:57:51 INFO - PID 4620 | 14:57:51 INFO - PID 4620 | [#0] ai-astar Cycles:10 Average:111.20 Median:111.50 stddev:3.29 (3.0%) stddev-sans-first:1.69 14:57:51 INFO - PID 4620 | Values: 103.0 111.0 112.0 111.0 111.0 111.0 112.0 115.0 115.0 111.0 14:57:51 INFO - PID 4620 | 14:57:51 INFO - PID 4620 | [#1] audio-beat-detection Cycles:10 Average:133.40 Median:124.00 stddev:16.65 (13.4%) stddev-sans-first:14.08 14:57:51 INFO - PID 4620 | Values: 162.0 123.0 124.0 162.0 124.0 123.0 122.0 124.0 146.0 124.0 14:57:51 INFO - PID 4620 | 14:57:51 INFO - PID 4620 | [#2] audio-dft Cycles:10 Average:162.20 Median:162.50 stddev:1.03 (0.6%) stddev-sans-first:1.00 14:57:51 INFO - PID 4620 | Values: 161.0 162.0 163.0 163.0 164.0 161.0 162.0 163.0 161.0 162.0 14:57:51 INFO - PID 4620 | 14:57:51 INFO - PID 4620 | [#3] audio-fft Cycles:10 Average:79.80 Median:75.50 stddev:11.19 (14.8%) stddev-sans-first:11.67 14:57:51 INFO - PID 4620 | Values: 74.0 74.0 76.0 109.0 75.0 75.0 76.0 76.0 89.0 74.0 14:57:51 INFO - PID 4620 | 14:57:51 INFO - PID 4620 | [#4] audio-oscillator Cycles:10 Average:91.80 Median:83.00 stddev:13.64 (16.4%) stddev-sans-first:14.18 14:57:51 INFO - PID 4620 | Values: 84.0 83.0 119.0 85.0 83.0 83.0 102.0 85.0 83.0 111.0 14:57:51 INFO - PID 4620 | 14:57:51 INFO - PID 4620 | [#5] imaging-gaussian-blur Cycles:10 Average:113.00 Median:113.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 14:57:51 INFO - PID 4620 | Values: 113.0 113.0 113.0 113.0 113.0 113.0 113.0 113.0 113.0 113.0 14:57:51 INFO - PID 4620 | 14:57:51 INFO - PID 4620 | [#6] imaging-darkroom Cycles:10 Average:148.70 Median:149.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 14:57:51 INFO - PID 4620 | Values: 149.0 148.0 149.0 150.0 149.0 149.0 149.0 148.0 148.0 148.0 14:57:51 INFO - PID 4620 | 14:57:51 INFO - PID 4620 | [#7] imaging-desaturate Cycles:10 Average:93.60 Median:94.00 stddev:0.70 (0.7%) stddev-sans-first:0.73 14:57:51 INFO - PID 4620 | Values: 94.0 93.0 93.0 94.0 94.0 95.0 93.0 94.0 93.0 93.0 14:57:51 INFO - PID 4620 | 14:57:51 INFO - PID 4620 | [#8] json-parse-financial Cycles:10 Average:64.50 Median:65.00 stddev:1.18 (1.8%) stddev-sans-first:1.24 14:57:51 INFO - PID 4620 | Values: 64.0 64.0 62.0 64.0 64.0 66.0 66.0 65.0 65.0 65.0 14:57:51 INFO - PID 4620 | 14:57:51 INFO - PID 4620 | [#9] json-stringify-tinderbox Cycles:10 Average:55.50 Median:55.50 stddev:0.71 (1.3%) stddev-sans-first:0.73 14:57:51 INFO - PID 4620 | Values: 56.0 55.0 55.0 57.0 56.0 55.0 55.0 55.0 55.0 56.0 14:57:51 INFO - PID 4620 | 14:57:51 INFO - PID 4620 | [#10] stanford-crypto-aes Cycles:10 Average:83.00 Median:83.00 stddev:0.82 (1.0%) stddev-sans-first:0.78 14:57:51 INFO - PID 4620 | Values: 84.0 83.0 83.0 84.0 82.0 82.0 82.0 83.0 84.0 83.0 14:57:51 INFO - PID 4620 | 14:57:51 INFO - PID 4620 | [#11] stanford-crypto-ccm Cycles:10 Average:142.40 Median:139.50 stddev:13.14 (9.4%) stddev-sans-first:13.88 14:57:51 INFO - PID 4620 | Values: 139.0 139.0 132.0 137.0 138.0 142.0 140.0 146.0 178.0 133.0 14:57:51 INFO - PID 4620 | 14:57:51 INFO - PID 4620 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:194.50 Median:195.50 stddev:6.10 (3.1%) stddev-sans-first:5.41 14:57:51 INFO - PID 4620 | Values: 185.0 195.0 194.0 196.0 196.0 207.0 188.0 190.0 195.0 199.0 14:57:51 INFO - PID 4620 | 14:57:51 INFO - PID 4620 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.60 Median:71.00 stddev:1.78 (2.5%) stddev-sans-first:1.39 14:57:51 INFO - PID 4620 | Values: 75.0 70.0 73.0 74.0 71.0 71.0 71.0 71.0 70.0 70.0 14:57:51 INFO - PID 4620 | -------- Summary: end -------- 14:57:51 INFO - PID 4620 | 14:57:52 INFO - TEST-INFO | 4620: exit 0 14:57:52 INFO - TEST-OK | kraken | took 64189ms 14:57:52 INFO - SUITE-END | took 1187s 14:57:52 INFO - Completed test suite (00:19:47) 14:57:52 INFO - javascript benchmark 14:57:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6549.008886299636, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2668.786497080234, 2718.8096833221684, 2742.698303186649, 2753.5594304911206, 2755.683096443908, 5093.821716239932, 5144.485551444863, 5236.371635403752, 5246.5278124968745, 5269.077911365505, 5129.589632829374, 5158.0, 5204.713740744258, 5211.218317252405, 5239.083160446926, 4703.835365762199, 4705.364775755272, 4711.976440117812, 4740.620750339965, 4772.188727916246, 4777.140114779339, 4809.302651115569, 4815.133276010317, 4823.855284341475, 4846.927296090544, 4614.077184563101, 4650.790714417859, 4651.627869770444, 4693.6949098308505, 4739.644526660514, 4726.125666751662, 4731.290306454025, 4736.0, 4782.497837727024, 4797.592204662608, 4658.324542941288, 4676.766161691902, 4697.906041879178, 4717.9292310615365, 4741.691790033637, 5057.5701065409485, 5103.56619687327, 5196.558292545138, 5198.662086964373, 5228.137357336073, 5498.477644623755, 5517.117261238222, 5557.916631250535, 5564.137558678412, 5567.749451274703, 5641.830745077613, 5651.2370829937845, 5658.292713410824, 5663.037283661787, 5664.40523745005, 5230.895382092375, 5244.816431424882, 5247.2653828464045, 5251.52736253739, 5300.655457395257, 5576.553875689935, 5639.210510528529, 5639.605227634108, 5717.028105222123, 5753.798617048383, 5491.478309560585, 5491.890162196733, 5495.697736624444, 5499.670019798745, 5545.889082218333, 5551.666899986014, 5558.722063896789, 5619.409961953936, 5620.971895140498, 5622.212890195376, 5401.810936617199, 5434.673919564839, 5435.7825686972865, 5569.916451253234, 5627.380988091307, 5489.560835133179, 5546.972265138729, 5548.140038294071, 5549.944500555023, 5556.166575013699, 4710.764461776966, 4716.952830471651, 4717.8112875485285, 4725.054989002213, 4768.165571025057, 4028.67770578353, 4033.173199494155, 4039.939400908989, 4048.8785336439955, 4089.0186355275114, 4655.045715628288, 4673.719576825401, 4723.197056500335, 4765.189917713997, 4804.966932109543, 5573.359063707726, 5612.326520817446, 5616.747246373841, 5635.520980716685, 5670.659760414388, 4784.976075119672, 4795.065658575352, 4795.832145874878, 4798.832040878552, 4841.878953026203, 4910.410750709938, 4943.060818444484, 4950.430700469492, 5008.774605142778, 5013.1227035268685, 5118.820841270538, 5131.153359695609, 5153.7680804364645, 5156.40701319338, 5177.792888284426, 5494.313210848644, 5606.0, 5641.12562552805, 5646.802361917313, 5655.830325090254, 5252.5535329497425, 5267.209918512254, 5270.31485906845, 5271.920921186262, 5275.762590683506, 5491.68078110983, 5527.806526771544, 5537.667739935617, 5540.556755459473, 5602.747876345656, 5443.591730620298, 5513.365962914316, 5549.056660367666, 5555.944440555704, 5591.692456914909, 5427.348718153847, 5433.1035379161995, 5440.972795136157, 5441.564674825925, 5459.153831156177, 5417.079096553674, 5419.376771671308, 5419.376771671308, 5422.864428389322, 5430.592705547178, 5208.661437006583, 5265.21021846726, 5291.735413229246, 5312.415634280149, 5337.572994160536, 5455.536279416294, 5459.808906688247, 5460.481254280837, 5504.256925315191, 5508.972455137859, 5263.921041184309, 5275.024120537712, 5282.709450980233, 5287.251087205182, 5340.5994550408095], "value": 5083.36162173892, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [610.7465401858229, 648.079726787961, 666.7533012785269, 1070.0155856611916, 1091.1052936591998, 25973.220803375905, 26561.070362537313, 26612.334691632695, 26620.0, 26637.866810665968, 31046.37907241854, 31840.84079579599, 31858.0, 31859.840700796463, 31880.840595797046, 7185.173705023937, 7348.816279592999, 7353.77938661841, 7355.301246381585, 7357.742479013235, 7163.785086447415, 7297.270272972713, 7417.629118544078, 7425.071866016748, 7426.665800039012, 5482.2873026506495, 5671.971640141794, 7247.963760181218, 7425.962870185642, 7427.888581671279, 7407.222241664628, 7477.401807855358, 7491.925080749177, 7589.8861517077285, 7622.656980435893, 7388.44586656003, 7489.775306740807, 7494.962525187339, 7495.437842161805, 7496.325330720261, 22902.0, 23540.529189416116, 28928.094707214466, 29510.0, 29528.0, 2661.334666333417, 2663.3607934095676, 2666.7999900007385, 2668.9332766680795, 2714.7013828478853, 22122.52198539279, 22763.317100487013, 22785.20251791196, 23013.424664383358, 23281.53436931269, 2972.18264977133, 3000.534917087855, 3005.2186431527743, 3026.7124623160767, 3047.024952015356, 150938.0, 151738.24130879386, 151801.24099379542, 151865.0, 152075.0, 43228.78385608115, 43244.56755432405, 43318.783406082766, 43328.56671433245, 43343.0, 45225.547744522795, 45430.318545221846, 45494.0, 45634.771826140655, 45785.7710711451, 13267.0, 13515.662108447368, 13553.796693049611, 13594.93202533981, 13621.114627549177, 11000.834987475195, 11006.669799906016, 11010.339379637247, 11013.559457621766, 11019.338839669485, 10229.641962531276, 12061.939690301493, 12507.812382814265, 12611.495540178474, 12708.809367859489, 4403.713758605744, 4486.730796152241, 4550.544945505423, 4587.61005314552, 4808.27875818623, 854.918782715641, 874.1302404107827, 877.6313948141795, 889.4129874282942, 890.2121622364255, 18545.35091271799, 19309.51726206856, 19789.901050494656, 19827.30604428838, 19904.50238744043, 936.8594710793299, 946.2382781860669, 951.1962391778935, 953.5613617735919, 957.593022965237, 10621.362718236931, 10840.349579025284, 10851.78296434067, 10858.619948301772, 10972.945135274433, 199461.0, 200129.0, 200130.0, 200156.0, 200200.0, 41785.79107104445, 41847.815218478514, 41920.580794192276, 41936.16127677429, 41972.0, 1193.8519603704576, 1228.8033914573625, 1230.280286032663, 1234.6357824441811, 1236.8824961628811, 19255.32606358771, 19434.319798806973, 19513.70729439088, 20756.584868302853, 20882.68675969892, 30573.0, 30677.386452270384, 30707.692923071376, 30729.385412292075, 30753.0, 216577.0, 216848.0, 217135.0, 217348.0, 217433.0, 27143.321416964733, 28214.43571128607, 28229.153125406272, 28239.858800705864, 28779.424411511234, 34725.30549389048, 34820.129496762784, 36419.63580364163, 36785.08037299089, 37561.624383755814, 27590.310242244104, 27755.444891101663, 27867.860660697377, 28039.299017524725, 28759.568606471337, 27502.724972750017, 27743.722562774114, 28014.0, 28117.43765124727, 28545.571816422354, 30504.694953051072, 30663.84668076645, 30782.692173077983, 31287.0, 31506.0, 26611.201663950113, 26799.86600066987, 26884.193474195807, 27048.1885543434, 27491.31271718223, 20897.477563061046, 21872.12511499586, 22029.449263768533, 22043.33869983903, 22279.331620051424], "value": 14456.725832774131, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [381.91216020315346, 419.54479389862007, 428.7513242319455, 440.3988555621577, 444.9154660614483, 12854.421551030202, 17527.737083943735, 17624.118794060294, 18302.267909283644, 18321.908390458062, 18764.71852922207, 18805.871647701148, 18946.905265473688, 19157.329493467732, 19248.711269330935, 5659.151127330892, 5869.941300587004, 5874.765009399619, 5876.44173803488, 5879.617824841393, 3438.673326034023, 3602.5676918769786, 5472.151816468454, 5663.2637757091725, 5664.461876121783, 5657.575681823859, 5665.433456654322, 5665.716714164296, 5674.517665998395, 5682.0, 5471.069918113911, 5637.23897273871, 5696.344920334173, 5704.857378565527, 5752.079667253241, 5102.617303702237, 5128.9230661540105, 5136.203888397305, 5138.306328645623, 5197.116490196638, 22275.220367287227, 22879.7712022881, 22904.885475572682, 22999.425014374607, 23157.0737170513, 1548.992255038729, 1578.2897696036714, 1586.6430053238043, 1597.5846279967177, 1624.3827345608593, 13855.584332470042, 13934.58196254114, 14081.64795880101, 14398.568042958728, 14620.488282910048, 1729.4292883348592, 1768.151287382051, 1785.4286628278956, 1800.5244919913323, 1806.4309742431092, 84695.57652211761, 86998.56500717456, 88236.0, 89640.55179724125, 90768.0, 9513.14381705636, 10627.202959778047, 10770.78458430827, 10803.891961080288, 10971.83542246867, 13578.321083945762, 13966.86033139676, 14436.566902992927, 14438.422463101568, 14503.1298122113, 7239.565626062348, 7403.370713489414, 7422.0, 7498.100227972678, 7519.586422746692, 6788.9660551696925, 6794.082798822095, 6963.0, 6975.267596902354, 6989.930100698928, 6853.383195512328, 6883.621400822903, 6924.134483189601, 6933.514653974174, 6964.477664175207, 2031.8476114291486, 2033.4814622271226, 2034.4710375302232, 2040.0717673458541, 2194.9451263718533, 828.1842385250524, 865.5023361567124, 866.2247288676599, 870.3907264914585, 878.3236907581254, 16299.674006519803, 16307.592310192103, 16596.0, 17089.0, 17249.310027598756, 845.6617353058703, 878.3895192841042, 884.3765145572356, 884.8539990901558, 886.8270687215932, 8653.913460865437, 8737.956310218407, 9219.769505762275, 9384.765380865396, 9504.382215155993, 317772.0, 322535.0, 323177.0, 456098.0, 463566.0, 75920.0, 78645.0, 84794.0, 94298.71212915667, 96525.0, 1099.1756182862853, 1143.1197977557401, 1148.121686909515, 1152.8904754048351, 1162.8604567451791, 16584.83415165881, 16712.749308760136, 17274.309027638752, 17634.118294084987, 18015.72976405329, 35067.298654026265, 41104.58895411008, 41200.793996029824, 41299.38050929298, 42029.579704202566, 52632.73683631558, 54313.0, 55194.0, 55281.44718552763, 55963.720181398836, 7319.34125928669, 7548.773536793905, 7654.119776225805, 7811.101723301893, 8033.799155020937, 9122.27021838238, 10659.14726821868, 10688.57245710194, 10988.340699558052, 11795.233309834834, 9652.27607929422, 9807.215422766305, 10418.843717344398, 10787.08309793645, 10841.566337346734, 8570.52862092566, 9621.422714637145, 10054.748631284276, 10177.440240786827, 10232.948835255776, 10402.0, 10776.730581735519, 10820.783584328112, 10906.890931090587, 11067.280626759235, 10969.341839489656, 11184.832227516432, 11256.380899050631, 11286.0, 11396.373199474108, 10722.839157412802, 10759.462026898467, 10826.13390928708, 11286.266392684449, 11298.0], "value": 8790.394695620775, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1276.5978716704237, 1326.1910234756797, 1344.6167842164973, 1346.3066520741813, 1350.3585796746543, 9479.09948554887, 9883.061109194632, 9885.703428897135, 9897.15874150698, 10200.285979981405, 9729.805403891918, 9936.155426788713, 9957.302988790787, 10044.296899217057, 10057.094861462467, 2215.523662412581, 2247.5954328220914, 2249.5950728868797, 2252.876091814958, 2259.58197733419, 1770.283035370677, 2114.651082571374, 2206.3160420269687, 2246.2475070851283, 2251.3696165073725, 1874.9250029998784, 2044.836413086957, 2141.1114387529155, 2143.9356819295367, 2144.281665642012, 2193.670949357593, 2237.0827960536185, 2278.464560828204, 2280.84034117611, 2282.3495303838326, 2265.943351416228, 2302.3553405046614, 2302.5740238056014, 2303.7120359955006, 2307.3885420363617, 9760.609575616967, 9837.0, 10099.29304948654, 10121.139703125244, 10131.64539241123, 5503.532199763025, 5746.0, 5747.0230060889335, 5748.971255143698, 5756.395578464285, 3061.3571150058515, 3093.3194697166587, 3269.820159891205, 3288.8684452621865, 3303.8182899940493, 1031.4481752262504, 1048.7483004078965, 1069.9251052426334, 1070.5664205996584, 1074.0441007503327, 14512.27438628075, 14974.0, 15099.924500377538, 15117.09297442157, 15141.924290378587, 4893.192623217201, 4952.950470495321, 5013.774380152902, 5016.824411145592, 5125.026245013435, 12084.697882553006, 12097.334646594521, 12128.69678258033, 12133.332666703416, 12141.0, 5640.097584386478, 5646.20953066571, 5648.406917273709, 5656.179853921159, 5663.037283661787, 4973.90052198954, 5065.341505604248, 5105.8723531911255, 5132.897342053138, 5138.511841375063, 4056.698138524947, 4145.0880806222585, 4152.534376846733, 4189.937150942738, 4205.600467955539, 1244.9315287659265, 1246.2335663566842, 1269.6825793551611, 1293.6636474516506, 1297.9351032448528, 563.1890078287335, 647.4011539326199, 721.5923003503004, 726.8982342472058, 742.9962850185715, 5577.776888924479, 5821.621594596338, 6250.937490625036, 6295.307516173309, 6301.6219026858535, 838.7316058861228, 843.6667516331015, 846.5386364431399, 857.1171693156059, 867.700643278068, 3611.7110631149444, 3654.2691461707764, 3668.908722028418, 3736.663700266989, 3942.7240093193773, 12604.873951260553, 13004.284764337863, 13142.934285328512, 13224.603261902159, 13243.139195952232, 12953.740925181444, 13079.41142648583, 13098.0, 13101.082924195214, 13126.081174317706, 8855.557222138867, 8887.644494220158, 8995.77510562228, 9016.323775716848, 9019.684311049083, 5560.972195138998, 5561.5272701820795, 5589.972050139723, 5706.058500347396, 5813.534917206698, 11722.706932326419, 11725.648230553097, 11740.647780566596, 11845.46009018822, 11873.406329683308, 12235.571754988532, 12237.143399961917, 12249.020078393529, 12258.938705306417, 12289.0, 7793.4154938378715, 7805.375569954504, 7842.176571459914, 7945.761627151194, 7958.761237162895, 8834.779130521789, 9246.95376523113, 9336.673216437392, 9353.11145441182, 9353.485558294358, 7495.400367970658, 7498.175200728025, 7509.4367922404945, 7535.472516923763, 7543.094828620381, 7248.818779530343, 7271.818204544717, 7501.062367204099, 7619.161892191743, 7657.1959944205055, 7093.893591596027, 7112.466565007541, 7177.641117943977, 7209.567425954249, 7209.855802884018, 5163.3545806774155, 5172.870678233075, 5220.477952204749, 5331.09371406854, 5409.242706021081, 5151.330327057459, 5201.453847345974, 5235.65968212065, 5304.920426193533, 5400.216968539618], "value": 4751.067249780859, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [372.5027088836403, 390.46896221139247, 391.907901643114, 393.65555139253155, 396.2214249000714, 5374.408815030349, 5435.673859568428, 5444.631585260409, 5466.617336786427, 5469.617126801126, 5118.436971933093, 5282.815101471439, 5314.601404894629, 5326.2543243945875, 5369.194620806871, 4138.97930510347, 4143.585641435855, 4143.792810359485, 4143.97928010361, 4149.211649786546, 4132.710710250284, 4147.232761939036, 4154.106867023589, 4163.20899029185, 4176.352665336863, 3943.940840887389, 4087.0, 4100.5489396166395, 4134.607212314825, 4136.358864376011, 4076.3885417187657, 4084.14233011068, 4085.836566537335, 4089.3048181809163, 4100.7334523255895, 3571.3035957988204, 3573.5711714594413, 3575.6781889629797, 3578.2127931854952, 3591.9281614367565, 4934.358533390672, 5173.9223911640975, 5184.196449550326, 5212.817551385683, 5224.294720212761, 4690.249560070372, 4709.788059537294, 4711.128441238382, 4724.834630787906, 4726.810927562893, 5575.944240557583, 5578.693171875545, 5605.355384130837, 5624.971875140639, 5647.322321321427, 3844.1158533537164, 3959.3467077932105, 3973.5827738087664, 3975.5626881043063, 3977.1449138435373, 5550.805721799758, 5755.280589926259, 5768.509676677488, 5785.27684039495, 5874.32445268791, 3993.900152496211, 3998.520177578709, 3999.140184860211, 4004.939925901114, 4005.859794907164, 4041.515018197835, 4042.211768705074, 4050.5544390117066, 4053.918921621551, 4078.8368465261055, 4423.292273236267, 4461.386277115546, 4513.571210734975, 4682.672212945061, 4690.343351930733, 5603.467670571289, 5620.803271885465, 5621.915671264935, 5632.802851900164, 5646.661200327993, 1400.7408629403592, 1422.0, 1422.8150340455675, 1423.843377228504, 1425.7504936636046, 912.5665308978182, 953.2040745977187, 964.3346091197192, 971.6793458158791, 975.7609385700549, 4903.4606193318705, 4940.925886111711, 4942.036302920896, 4948.356713627206, 4951.455339912607, 5807.622504537191, 5810.1575271586225, 5826.242588463472, 5826.650400975955, 5832.650040997469, 4746.454157771831, 4863.586595139453, 4914.778834952435, 4916.532929371704, 4930.334404855299, 3038.7872848900806, 3067.831269280211, 3067.8466076696077, 3069.4014667139695, 3073.1548824073557, 5378.91931621026, 5401.648892821954, 5405.270288511001, 5406.567474602022, 5439.945600543944, 5653.293338332708, 5818.796342128006, 5896.0, 5908.379620139909, 5916.171735956969, 5372.57019438444, 5377.973110134502, 5383.5423988961375, 5391.056565101092, 5408.161734931092, 5566.248556444828, 5753.770978331007, 5757.798477053283, 5759.65442073477, 5818.4763371296785, 5647.124695672177, 5666.490015898589, 5676.978143933967, 5688.829335119953, 5696.715164241688, 5114.851762259786, 5279.39286982002, 5303.257543943928, 5303.602229832701, 5361.329833770778, 3770.8114594269628, 3888.3389823729467, 3892.9221415572097, 3986.880393588197, 4036.7779772112817, 5795.7391917362975, 5800.738966746422, 5819.621724587888, 5825.0, 5832.504237139721, 5498.725063746717, 5605.355384130877, 5643.52030077448, 5647.943520564906, 5651.6043876928225, 5605.775768969195, 5608.9158662619275, 5621.7189140542, 5627.114916502854, 5634.774609015593, 5304.469553044664, 5508.338999319947, 5526.50261476469, 5536.806211782568, 5546.889062218653, 5632.0, 5640.802571909963, 5653.293338332708, 5663.801766938137, 5673.205751194919, 5423.566114710892, 5432.809851655173, 5443.7822487100075, 5473.808416705396, 5476.424975377528], "value": 4212.06440964224, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [682.4892062848353, 700.1912790726713, 704.718112754898, 706.0926709178707, 707.377507793142, 12687.238765674054, 13141.67145821355, 13151.605451836436, 13160.34198290084, 13165.736685266302, 12415.751684966295, 12581.678923713016, 14073.929630351833, 14569.271536423163, 14773.113613183217, 814.3322475570035, 831.5883637599381, 839.1189251286156, 851.5061264466594, 854.8888644476209, 838.932885369169, 839.5382539603224, 840.4873027453249, 840.604915689628, 842.5281842168405, 876.4084243135858, 878.8549889268295, 884.1512148337604, 884.5311984648115, 884.9601767920427, 870.5995242188601, 870.8214815962775, 870.9390342676015, 871.5206636350014, 876.9605367758467, 651.0982289528989, 652.54974067893, 652.624740774052, 653.0204692960559, 653.0269897852213, 13368.732625347438, 13382.397792099378, 13430.798538021938, 13507.0, 14355.928220358936, 13407.597772066854, 14266.215358155298, 14292.714145717131, 14375.712485750331, 14395.0, 10641.840372394343, 10658.946705266502, 10669.413182274971, 10876.673699789018, 10940.781184376348, 2741.739534744196, 2747.615333853262, 2752.4357506711076, 2799.4261176458776, 2857.6856545780156, 64875.67562162159, 65851.341486585, 65938.3406165937, 66005.66997164984, 66453.66773166103, 10619.787604248026, 10635.627753028606, 10734.355938643706, 10761.0, 10824.78350432987, 20994.160233590486, 21054.684179737316, 21206.787932120482, 21319.467013324793, 21411.67882481764, 6445.129907462527, 6474.384933431316, 6493.123428337209, 6547.312532184147, 6649.301823308483, 3229.6770322967513, 3296.8516416761304, 3333.4833100869405, 3352.2122301259183, 3392.9321413571593, 3449.2756521130846, 3461.3250416169108, 3509.964900350964, 3518.3315170117594, 3527.417976033977, 628.2868943748878, 631.5831551176293, 635.5138319185827, 641.5060403489381, 648.2383199740286, 496.4340651657114, 517.2913109040638, 522.947705229474, 554.6810583914265, 554.7780887644893, 7963.681452741941, 8316.022745864142, 8585.441946273471, 8616.870746938801, 8678.435901666371, 530.3556179242162, 531.5614617940215, 538.4611556511069, 541.507228422134, 564.7966731976485, 3713.8514459421863, 4086.570910054461, 4163.0, 4397.670174736908, 4441.400410944546, 64130.70104834866, 64432.0, 64652.67673661601, 65885.67057164683, 66141.33858661448, 33155.0, 40076.398854017214, 44336.0, 44417.33373999393, 45653.0, 40671.0, 46691.83270418267, 46738.0, 47736.52263477321, 47850.60746962638, 8447.662093516436, 8977.371583989057, 9015.233705135137, 9056.501892395981, 9211.815763684555, 23810.523789524457, 24210.15264465735, 24497.0, 24840.751592483844, 24867.62698559559, 29947.850260748557, 30207.848960755055, 30335.696643033287, 30541.694583054774, 30920.845395772878, 10778.13774898022, 10852.511636976196, 10920.071793897609, 11058.502367393647, 11149.275297105662, 11537.826932596186, 11834.171607987359, 12126.575569855013, 12319.50721971147, 12550.811737823757, 10607.4165920872, 10886.346819190874, 11025.944870275918, 11325.660230193107, 11414.94292528532, 11504.94247528757, 11580.189386742848, 11639.59261425846, 11805.23265987708, 12082.275063496218, 10925.617603383844, 11256.21206515536, 11475.655730328104, 11475.655730328104, 11684.0, 7411.62941852916, 7532.359749421361, 7819.765407037798, 7896.973393458814, 8047.0, 8027.678892844221, 8167.305779008613, 8203.917960820316, 8219.558384643547, 8375.958120209603], "value": 6102.465068063834, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1545.1999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 111.0, 112.0, 111.0, 111.0, 111.0, 112.0, 115.0, 115.0, 111.0], "value": 111.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 123.0, 124.0, 162.0, 124.0, 123.0, 122.0, 124.0, 146.0, 124.0], "value": 133.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 163.0, 163.0, 164.0, 161.0, 162.0, 163.0, 161.0, 162.0], "value": 162.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 76.0, 109.0, 75.0, 75.0, 76.0, 76.0, 89.0, 74.0], "value": 79.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 119.0, 85.0, 83.0, 83.0, 102.0, 85.0, 83.0, 111.0], "value": 91.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0], "value": 113.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 148.0, 149.0, 150.0, 149.0, 149.0, 149.0, 148.0, 148.0, 148.0], "value": 148.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 93.0, 94.0, 94.0, 95.0, 93.0, 94.0, 93.0, 93.0], "value": 93.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 62.0, 64.0, 64.0, 66.0, 66.0, 65.0, 65.0, 65.0], "value": 64.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 55.0, 57.0, 56.0, 55.0, 55.0, 55.0, 55.0, 56.0], "value": 55.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 83.0, 84.0, 82.0, 82.0, 82.0, 83.0, 84.0, 83.0], "value": 83.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 139.0, 132.0, 137.0, 138.0, 142.0, 140.0, 146.0, 178.0, 133.0], "value": 142.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 195.0, 194.0, 196.0, 196.0, 207.0, 188.0, 190.0, 195.0, 199.0], "value": 194.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 70.0, 73.0, 74.0, 71.0, 71.0, 71.0, 71.0, 70.0, 70.0], "value": 71.6, "unit": "score"}]}]} 14:57:52 INFO - Return code: 0 14:57:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:57:52 ERROR - # TBPL SUCCESS # 14:57:52 INFO - Running post-action listener: _resource_record_post_action 14:57:52 INFO - [mozharness: 2017-03-24 21:57:52.836815Z] Finished run-tests step (success) 14:57:52 INFO - Running post-run listener: _resource_record_post_run 14:57:52 INFO - Total resource usage - Wall time: 1204s; CPU: 13.0%; Read bytes: 4096; Write bytes: 362954752; Read time: 8; Write time: 201872 14:57:52 INFO - TinderboxPrint: CPU usage
12.9% 14:57:52 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 14:57:52 INFO - TinderboxPrint: I/O write bytes / time
362,954,752 / 201,872 14:57:52 INFO - TinderboxPrint: CPU idle
8,365.3 (87.0%) 14:57:52 INFO - TinderboxPrint: CPU user
1,199.8 (12.5%) 14:57:52 INFO - TinderboxPrint: Swap in / out
0 / 0 14:57:52 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 105832448; Read time: 0; Write time: 81188 14:57:52 INFO - run-tests - Wall time: 1188s; CPU: 13.0%; Read bytes: 0; Write bytes: 256532480; Read time: 0; Write time: 120652 14:57:53 INFO - Running post-run listener: _upload_blobber_files 14:57:53 INFO - Blob upload gear active. 14:57:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:57:53 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 14:57:53 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', 'Pine', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:57:53 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 Pine -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:57:53 INFO - (blobuploader) - INFO - Open directory for files ... 14:57:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:57:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:57:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:57:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:57:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:57:54 INFO - (blobuploader) - INFO - Done attempting. 14:57:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:57:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:57:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:57:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:57:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:57:55 INFO - (blobuploader) - INFO - Done attempting. 14:57:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:57:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:57:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:57:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:57:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:57:58 INFO - (blobuploader) - INFO - Done attempting. 14:57:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 14:57:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:57:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:58:00 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 14:58:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:58:00 INFO - (blobuploader) - INFO - Done attempting. 14:58:00 INFO - (blobuploader) - INFO - Iteration through files over. 14:58:00 INFO - Return code: 0 14:58:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:58:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:58:00 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/83faf1ace3d61aadd80e453d525774521fbdc43062603632afac8f62cf247406e67ed0dd563d31340599b2c84e59b64b1821fb263a0776bca9531ea936148ed1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/28837887995d0174750c7f99bf89177d08265f180ebe32a1699f42f2c35ef75cfe99d38f2de06be7fa6d355bdb973c07b898b8f933d9cdfa0c6ef737c04892c8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/e594ff933fa4a5690c9c01db59c3919423dcfe221297771870597ce2b563b476dd11c1ef7c61e5fdcfa8cde2e2329b4ab0af2237b4f07439ea059432b43c3d17", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/2740a1e5654e62edc284de6c2c19757f238427ca76172c16c7c6be34794cb6bc07d2d6bd14da4fe809b48dc9331a0d41034818230940c963813ea4701e795aee"} 14:58:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:58:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:58:00 INFO - Contents: 14:58:00 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/83faf1ace3d61aadd80e453d525774521fbdc43062603632afac8f62cf247406e67ed0dd563d31340599b2c84e59b64b1821fb263a0776bca9531ea936148ed1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/28837887995d0174750c7f99bf89177d08265f180ebe32a1699f42f2c35ef75cfe99d38f2de06be7fa6d355bdb973c07b898b8f933d9cdfa0c6ef737c04892c8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/e594ff933fa4a5690c9c01db59c3919423dcfe221297771870597ce2b563b476dd11c1ef7c61e5fdcfa8cde2e2329b4ab0af2237b4f07439ea059432b43c3d17", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/2740a1e5654e62edc284de6c2c19757f238427ca76172c16c7c6be34794cb6bc07d2d6bd14da4fe809b48dc9331a0d41034818230940c963813ea4701e795aee"} 14:58:00 INFO - Running post-run listener: copy_logs_to_upload_dir 14:58:00 INFO - Copying logs to upload dir... 14:58:00 INFO - mkdir: /builds/slave/test/build/upload/logs 14:58:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.099370 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-03-24 14:58:00.033675) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-24 14:58:00.037156) ========= 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=bb9b54edd4a927dc735d112f000001d9-1490391416.248526-221435222 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/83faf1ace3d61aadd80e453d525774521fbdc43062603632afac8f62cf247406e67ed0dd563d31340599b2c84e59b64b1821fb263a0776bca9531ea936148ed1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/28837887995d0174750c7f99bf89177d08265f180ebe32a1699f42f2c35ef75cfe99d38f2de06be7fa6d355bdb973c07b898b8f933d9cdfa0c6ef737c04892c8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/e594ff933fa4a5690c9c01db59c3919423dcfe221297771870597ce2b563b476dd11c1ef7c61e5fdcfa8cde2e2329b4ab0af2237b4f07439ea059432b43c3d17", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/2740a1e5654e62edc284de6c2c19757f238427ca76172c16c7c6be34794cb6bc07d2d6bd14da4fe809b48dc9331a0d41034818230940c963813ea4701e795aee"} build_url:https://queue.taskcluster.net/v1/task/Tp_kezfbS2Od05imaKlSeg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011111 build_url: 'https://queue.taskcluster.net/v1/task/Tp_kezfbS2Od05imaKlSeg/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/83faf1ace3d61aadd80e453d525774521fbdc43062603632afac8f62cf247406e67ed0dd563d31340599b2c84e59b64b1821fb263a0776bca9531ea936148ed1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/28837887995d0174750c7f99bf89177d08265f180ebe32a1699f42f2c35ef75cfe99d38f2de06be7fa6d355bdb973c07b898b8f933d9cdfa0c6ef737c04892c8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/e594ff933fa4a5690c9c01db59c3919423dcfe221297771870597ce2b563b476dd11c1ef7c61e5fdcfa8cde2e2329b4ab0af2237b4f07439ea059432b43c3d17", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/2740a1e5654e62edc284de6c2c19757f238427ca76172c16c7c6be34794cb6bc07d2d6bd14da4fe809b48dc9331a0d41034818230940c963813ea4701e795aee"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-24 14:58:00.065128) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-24 14:58:00.065699) ========= 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=bb9b54edd4a927dc735d112f000001d9-1490391416.248526-221435222 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004438 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-24 14:58:00.147727) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-24 14:58:00.148073) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-24 14:58:00.148401) ========= ========= Total master_lag: 0.17 =========