builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-072 starttime: 1486690024.04 results: success (0) revision: 63752d3df9fa09ecd3283eaccb950cc5607fbab1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-09 17:27:04.040211) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-09 17:27:04.040677) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-09 17:27:04.040979) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-09 17:27:04.054070) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 17:27:04.054349) ========= 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=199fb4c72b90437b990b419b000001c5-1486690023.617762-1245543442 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-09 17:27:04-- 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% 68.5M=0s 2017-02-09 17:27:04 (68.5 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.150003 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 17:27:04.215526) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 17:27:04.215902) ========= 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=199fb4c72b90437b990b419b000001c5-1486690023.617762-1245543442 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016926 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 17:27:04.265347) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-09 17:27:04.265717) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 63752d3df9fa09ecd3283eaccb950cc5607fbab1 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 63752d3df9fa09ecd3283eaccb950cc5607fbab1 --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=199fb4c72b90437b990b419b000001c5-1486690023.617762-1245543442 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-09 17:27:04,506 truncating revision to first 12 chars 2017-02-09 17:27:04,506 Setting DEBUG logging. 2017-02-09 17:27:04,506 attempt 1/10 2017-02-09 17:27:04,506 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/63752d3df9fa?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-09 17:27:05,475 unpacking tar archive at: mozilla-aurora-63752d3df9fa/testing/mozharness/ program finished with exit code 0 elapsedTime=1.343899 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-09 17:27:05.622579) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-09 17:27:05.622900) ========= script_repo_revision: 63752d3df9fa09ecd3283eaccb950cc5607fbab1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-09 17:27:05.623286) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-09 17:27:05.623550) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-09 17:27:05.632484) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2017-02-09 17:27:05.632768) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=199fb4c72b90437b990b419b000001c5-1486690023.617762-1245543442 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:27:05 INFO - MultiFileLogger online at 20170209 17:27:05 in /builds/slave/test 17:27:05 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora-Non-PGO 17:27:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:27:05 INFO - {'append_to_log': False, 17:27:05 INFO - 'base_work_dir': '/builds/slave/test', 17:27:05 INFO - 'blob_upload_branch': 'Mozilla-Aurora-Non-PGO', 17:27:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:27:05 INFO - 'branch': 'Mozilla-Aurora-Non-PGO', 17:27:05 INFO - 'buildbot_json_path': 'buildprops.json', 17:27:05 INFO - 'config_files': ('talos/linux_config.py',), 17:27:05 INFO - 'default_actions': ('clobber', 17:27:05 INFO - 'read-buildbot-config', 17:27:05 INFO - 'download-and-extract', 17:27:05 INFO - 'populate-webroot', 17:27:05 INFO - 'create-virtualenv', 17:27:05 INFO - 'install', 17:27:05 INFO - 'run-tests'), 17:27:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:27:05 INFO - 'download_minidump_stackwalk': True, 17:27:05 INFO - 'download_symbols': 'ondemand', 17:27:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:27:05 INFO - 'tooltool.py': '/tools/tooltool.py', 17:27:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:27:05 INFO - '/tools/misc-python/virtualenv.py')}, 17:27:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:27:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:27:05 INFO - 'installer_path': 'installer.exe', 17:27:05 INFO - 'log_level': 'info', 17:27:05 INFO - 'log_name': 'talos', 17:27:05 INFO - 'log_to_console': True, 17:27:05 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:27:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:27:05 INFO - 'opt_config_files': (), 17:27:05 INFO - 'pip_index': False, 17:27:05 INFO - 'sps_profile': False, 17:27:05 INFO - 'sps_profile_interval': 0, 17:27:05 INFO - 'suite': 'dromaeojs', 17:27:05 INFO - 'system_bits': '32', 17:27:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:27:05 INFO - 'title': 'talos-linux64-ix-072', 17:27:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:27:05 INFO - 'use_talos_json': True, 17:27:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:27:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:27:05 INFO - 'work_dir': 'build'} 17:27:05 INFO - [mozharness: 2017-02-10 01:27:05.974000Z] Running clobber step. 17:27:05 INFO - Running pre-action listener: _resource_record_pre_action 17:27:05 INFO - Running main action method: clobber 17:27:05 INFO - rmtree: /builds/slave/test/build 17:27:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:27:06 INFO - Running post-action listener: _resource_record_post_action 17:27:06 INFO - [mozharness: 2017-02-10 01:27:06.951610Z] Finished clobber step (success) 17:27:06 INFO - [mozharness: 2017-02-10 01:27:06.951710Z] Running read-buildbot-config step. 17:27:06 INFO - Running pre-action listener: _resource_record_pre_action 17:27:06 INFO - Running main action method: read_buildbot_config 17:27:06 INFO - Using buildbot properties: 17:27:06 INFO - { 17:27:06 INFO - "project": "", 17:27:06 INFO - "product": "firefox", 17:27:06 INFO - "who": "ryanvm@gmail.com", 17:27:06 INFO - "installer_path": "public/build/target.tar.bz2", 17:27:06 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 17:27:06 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-aurora/", 17:27:06 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora talos dromaeojs", 17:27:06 INFO - "stage_platform": "linux64", 17:27:06 INFO - "basedir": "/builds/slave/test", 17:27:06 INFO - "buildnumber": 23, 17:27:06 INFO - "platform": "ubuntu64_hw", 17:27:06 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 17:27:06 INFO - "slavebuilddir": "test", 17:27:06 INFO - "taskId": "KwwU1TqCSFiDVXk6P2rneA", 17:27:06 INFO - "branch": "mozilla-aurora", 17:27:06 INFO - "script_repo_revision": "production", 17:27:06 INFO - "revision": "63752d3df9fa09ecd3283eaccb950cc5607fbab1", 17:27:06 INFO - "slavename": "talos-linux64-ix-072", 17:27:06 INFO - "repo_path": "releases/mozilla-aurora" 17:27:06 INFO - } 17:27:06 INFO - Finding installer, test and symbols from parent task. 17:27:06 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KwwU1TqCSFiDVXk6P2rneA'}, attempt #1 17:27:07 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KwwU1TqCSFiDVXk6P2rneA'}, attempt #1 17:27:07 INFO - Task dependencies: Tn4IGwBxS7669Hnno10MvQ 17:27:07 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Tn4IGwBxS7669Hnno10MvQ'}, attempt #1 17:27:08 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Tn4IGwBxS7669Hnno10MvQ/artifacts/public/build/target.tar.bz2 17:27:08 INFO - Set test_url: https://queue.taskcluster.net/v1/task/Tn4IGwBxS7669Hnno10MvQ/artifacts/public/build/test_packages.json 17:27:08 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Tn4IGwBxS7669Hnno10MvQ/artifacts/public/build/target.crashreporter-symbols.zip 17:27:08 INFO - Running post-action listener: _resource_record_post_action 17:27:08 INFO - [mozharness: 2017-02-10 01:27:08.439182Z] Finished read-buildbot-config step (success) 17:27:08 INFO - [mozharness: 2017-02-10 01:27:08.439400Z] Running download-and-extract step. 17:27:08 INFO - Running pre-action listener: _resource_record_pre_action 17:27:08 INFO - Running main action method: download_and_extract 17:27:08 INFO - mkdir: /builds/slave/test/build/tests 17:27:08 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')]} 17:27:08 INFO - trying https://queue.taskcluster.net/v1/task/Tn4IGwBxS7669Hnno10MvQ/artifacts/public/build/target.test_packages.json 17:27:08 INFO - Downloading https://queue.taskcluster.net/v1/task/Tn4IGwBxS7669Hnno10MvQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 17:27:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Tn4IGwBxS7669Hnno10MvQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 17:27:09 INFO - Downloaded 896 bytes. 17:27:09 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 17:27:09 INFO - Using the following test package requirements: 17:27:09 INFO - {u'common': [u'target.common.tests.zip'], 17:27:09 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 17:27:09 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 17:27:09 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 17:27:09 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 17:27:09 INFO - u'mozbase': [u'target.common.tests.zip'], 17:27:09 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 17:27:09 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 17:27:09 INFO - u'web-platform': [u'target.common.tests.zip', 17:27:09 INFO - u'target.web-platform.tests.zip'], 17:27:09 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 17:27:09 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 17:27:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Tn4IGwBxS7669Hnno10MvQ/artifacts/public/build/target.common.tests.zip 17:27:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Tn4IGwBxS7669Hnno10MvQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 17:27:09 INFO - Fetch https://queue.taskcluster.net/v1/task/Tn4IGwBxS7669Hnno10MvQ/artifacts/public/build/target.common.tests.zip into memory 17:27:09 INFO - Expected file size: 21858269 17:27:09 INFO - Obtained file size: 21858269 17:27:13 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 17:27:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Tn4IGwBxS7669Hnno10MvQ/artifacts/public/build/target.common.tests.zip 17:27:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Tn4IGwBxS7669Hnno10MvQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 17:27:13 INFO - Fetch https://queue.taskcluster.net/v1/task/Tn4IGwBxS7669Hnno10MvQ/artifacts/public/build/target.common.tests.zip into memory 17:27:14 INFO - Expected file size: 21858269 17:27:14 INFO - Obtained file size: 21858269 17:27:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Tn4IGwBxS7669Hnno10MvQ/artifacts/public/build/target.talos.tests.zip 17:27:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Tn4IGwBxS7669Hnno10MvQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 17:27:19 INFO - Fetch https://queue.taskcluster.net/v1/task/Tn4IGwBxS7669Hnno10MvQ/artifacts/public/build/target.talos.tests.zip into memory 17:27:19 INFO - Expected file size: 14403485 17:27:19 INFO - Obtained file size: 14403485 17:27:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:27:20 INFO - trying https://queue.taskcluster.net/v1/task/Tn4IGwBxS7669Hnno10MvQ/artifacts/public/build/target.tar.bz2 17:27:20 INFO - Downloading https://queue.taskcluster.net/v1/task/Tn4IGwBxS7669Hnno10MvQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 17:27:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Tn4IGwBxS7669Hnno10MvQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 17:27:21 INFO - Downloaded 66050631 bytes. 17:27:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Tn4IGwBxS7669Hnno10MvQ/artifacts/public/build/target.tar.bz2 17:27:21 INFO - mkdir: /builds/slave/test/properties 17:27:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:27:21 INFO - Writing to file /builds/slave/test/properties/build_url 17:27:21 INFO - Contents: 17:27:21 INFO - build_url:https://queue.taskcluster.net/v1/task/Tn4IGwBxS7669Hnno10MvQ/artifacts/public/build/target.tar.bz2 17:27:21 INFO - Running post-action listener: _resource_record_post_action 17:27:21 INFO - Running post-action listener: set_extra_try_arguments 17:27:21 INFO - [mozharness: 2017-02-10 01:27:21.516436Z] Finished download-and-extract step (success) 17:27:21 INFO - [mozharness: 2017-02-10 01:27:21.516533Z] Running populate-webroot step. 17:27:21 INFO - Running pre-action listener: _resource_record_pre_action 17:27:21 INFO - Running main action method: populate_webroot 17:27:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:27:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:27:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:27:21 INFO - '--tppagecycles', 17:27:21 INFO - '7'], 17:27:21 INFO - 'tests': ['tsvgm']}}, 17:27:21 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:27:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:27:21 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:27:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:27:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:27:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:27:21 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 17:27:21 INFO - 'talos_options': ['--disable-e10s'], 17:27:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:27:21 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:27:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:27:21 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 17:27:21 INFO - 'talos_options': ['--disable-e10s'], 17:27:21 INFO - 'tests': ['damp', 'tps']}, 17:27:21 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:27:21 INFO - 'tests': ['damp', 'tps']}, 17:27:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:27:21 INFO - 'tests': ['dromaeo_dom']}, 17:27:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:27:21 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:27:21 INFO - 'tests': ['basic_compositor_video']}, 17:27:21 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:27:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:27:21 INFO - 'tests': ['a11yr', 17:27:21 INFO - 'ts_paint', 17:27:21 INFO - 'tpaint', 17:27:21 INFO - 'sessionrestore', 17:27:21 INFO - 'sessionrestore_no_auto_restore', 17:27:21 INFO - 'tabpaint']}, 17:27:21 INFO - 'other-e10s': {'tests': ['a11yr', 17:27:21 INFO - 'ts_paint', 17:27:21 INFO - 'tpaint', 17:27:21 INFO - 'sessionrestore', 17:27:21 INFO - 'sessionrestore_no_auto_restore', 17:27:21 INFO - 'tabpaint']}, 17:27:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:27:21 INFO - 'ts_paint', 17:27:21 INFO - 'tpaint', 17:27:21 INFO - 'sessionrestore', 17:27:21 INFO - 'sessionrestore_no_auto_restore', 17:27:21 INFO - 'tabpaint']}, 17:27:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:27:21 INFO - 'ts_paint', 17:27:21 INFO - 'tpaint', 17:27:21 INFO - 'sessionrestore', 17:27:21 INFO - 'sessionrestore_no_auto_restore', 17:27:21 INFO - 'tabpaint']}, 17:27:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:27:21 INFO - 'tests': ['a11yr', 17:27:21 INFO - 'ts_paint', 17:27:21 INFO - 'tpaint', 17:27:21 INFO - 'sessionrestore', 17:27:21 INFO - 'sessionrestore_no_auto_restore', 17:27:21 INFO - 'tabpaint']}, 17:27:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:27:21 INFO - 'tests': ['a11yr', 17:27:21 INFO - 'ts_paint', 17:27:21 INFO - 'tpaint', 17:27:21 INFO - 'sessionrestore', 17:27:21 INFO - 'sessionrestore_no_auto_restore', 17:27:21 INFO - 'tabpaint']}, 17:27:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:27:21 INFO - 'tests': ['tsvgx', 17:27:21 INFO - 'tsvgr_opacity', 17:27:21 INFO - 'tart', 17:27:21 INFO - 'tscrollx', 17:27:21 INFO - 'cart', 17:27:21 INFO - 'tsvg_static']}, 17:27:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:27:21 INFO - 'tsvgr_opacity', 17:27:21 INFO - 'tart', 17:27:21 INFO - 'tscrollx', 17:27:21 INFO - 'cart', 17:27:21 INFO - 'tsvg_static']}, 17:27:21 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 17:27:21 INFO - 'talos_options': ['--disable-e10s'], 17:27:21 INFO - 'tests': ['tp5o']}, 17:27:21 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:27:21 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 17:27:21 INFO - 'talos_options': ['--disable-e10s', 17:27:21 INFO - '--xperf_path', 17:27:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:27:21 INFO - 'tests': ['tp5n']}, 17:27:21 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:27:21 INFO - 'talos_options': ['--xperf_path', 17:27:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:27:21 INFO - 'tests': ['tp5n']}}, 17:27:21 INFO - 'talos.zip': {'path': '', 17:27:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:27:21 INFO - Running post-action listener: _resource_record_post_action 17:27:21 INFO - [mozharness: 2017-02-10 01:27:21.529758Z] Finished populate-webroot step (success) 17:27:21 INFO - [mozharness: 2017-02-10 01:27:21.529849Z] Running create-virtualenv step. 17:27:21 INFO - Running pre-action listener: _resource_record_pre_action 17:27:21 INFO - Running main action method: create_virtualenv 17:27:21 INFO - Creating virtualenv /builds/slave/test/build/venv 17:27:21 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 17:27:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:27:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:27:21 INFO - Using real prefix '/usr' 17:27:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:27:22 INFO - Installing distribute.............................................................................................................................................................................................done. 17:27:24 INFO - Installing pip.................done. 17:27:24 INFO - Return code: 0 17:27:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:27:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:27:24 INFO - Reading from file tmpfile_stdout 17:27:24 INFO - Output received: 17:27:24 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 17:27:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:27: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')]} 17:27:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:27:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:27: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')]} 17:27:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:27:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:27:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13681f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1535a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16da980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16d7d00>, '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 0x16d8a00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16d8eb0>, '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': '199fb4c72b90437b990b419b000001c5-1486690023.617762-1245543442', '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 17:27:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 17:27:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:27:24 INFO - Using env: {'DISPLAY': ':0', 17:27:24 INFO - 'HOME': '/home/cltbld', 17:27:24 INFO - 'LANG': 'en_US.UTF-8', 17:27:24 INFO - 'LANGUAGE': 'en_US:en', 17:27:24 INFO - 'LOGNAME': 'cltbld', 17:27:24 INFO - 'MAIL': '/var/mail/cltbld', 17:27:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:27:24 INFO - 'MOZ_NO_REMOTE': '1', 17:27:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:27:24 INFO - 'NO_EM_RESTART': '1', 17:27:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:27:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:27:24 INFO - 'PWD': '/builds/slave/test', 17:27:24 INFO - 'SHELL': '/bin/bash', 17:27:24 INFO - 'SHLVL': '1', 17:27:24 INFO - 'TERM': 'linux', 17:27:24 INFO - 'TMOUT': '86400', 17:27:24 INFO - 'USER': 'cltbld', 17:27:24 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1486690023.617762-1245543442', 17:27:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:27:24 INFO - '_': '/tools/buildbot/bin/python'} 17:27:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:27:24 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 17:27:24 INFO - Cleaning up... 17:27:24 INFO - Return code: 0 17:27:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:27: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')]} 17:27:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:27:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:27: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')]} 17:27:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:27:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:27:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13681f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1535a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16da980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16d7d00>, '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 0x16d8a00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16d8eb0>, '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': '199fb4c72b90437b990b419b000001c5-1486690023.617762-1245543442', '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 17:27:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 17:27:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 17:27:24 INFO - Using env: {'DISPLAY': ':0', 17:27:24 INFO - 'HOME': '/home/cltbld', 17:27:24 INFO - 'LANG': 'en_US.UTF-8', 17:27:24 INFO - 'LANGUAGE': 'en_US:en', 17:27:24 INFO - 'LOGNAME': 'cltbld', 17:27:24 INFO - 'MAIL': '/var/mail/cltbld', 17:27:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:27:24 INFO - 'MOZ_NO_REMOTE': '1', 17:27:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:27:24 INFO - 'NO_EM_RESTART': '1', 17:27:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:27:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:27:24 INFO - 'PWD': '/builds/slave/test', 17:27:24 INFO - 'SHELL': '/bin/bash', 17:27:24 INFO - 'SHLVL': '1', 17:27:24 INFO - 'TERM': 'linux', 17:27:24 INFO - 'TMOUT': '86400', 17:27:24 INFO - 'USER': 'cltbld', 17:27:24 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1486690023.617762-1245543442', 17:27:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:27:24 INFO - '_': '/tools/buildbot/bin/python'} 17:27:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:27:24 INFO - Downloading/unpacking psutil>=3.1.1 17:27:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:27:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:27:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:27:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:27:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:27:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:27:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:27:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:27:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:27:26 INFO - Installing collected packages: psutil 17:27:26 INFO - Running setup.py install for psutil 17:27:26 INFO - building 'psutil._psutil_linux' extension 17:27:26 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 17:27:27 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 17:27:27 INFO - building 'psutil._psutil_posix' extension 17:27:27 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 17:27:27 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 17:27:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:27:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:27:27 INFO - Successfully installed psutil 17:27:27 INFO - Cleaning up... 17:27:27 INFO - Return code: 0 17:27:27 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:27:27 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')]} 17:27:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:27:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:27:27 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')]} 17:27:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:27:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:27:27 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 0x13681f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1535a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16da980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16d7d00>, '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 0x16d8a00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16d8eb0>, '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': '199fb4c72b90437b990b419b000001c5-1486690023.617762-1245543442', '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 17:27:27 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 17:27:27 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 17:27:27 INFO - Using env: {'DISPLAY': ':0', 17:27:27 INFO - 'HOME': '/home/cltbld', 17:27:27 INFO - 'LANG': 'en_US.UTF-8', 17:27:27 INFO - 'LANGUAGE': 'en_US:en', 17:27:27 INFO - 'LOGNAME': 'cltbld', 17:27:27 INFO - 'MAIL': '/var/mail/cltbld', 17:27:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:27:27 INFO - 'MOZ_NO_REMOTE': '1', 17:27:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:27:27 INFO - 'NO_EM_RESTART': '1', 17:27:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:27:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:27:27 INFO - 'PWD': '/builds/slave/test', 17:27:27 INFO - 'SHELL': '/bin/bash', 17:27:27 INFO - 'SHLVL': '1', 17:27:27 INFO - 'TERM': 'linux', 17:27:27 INFO - 'TMOUT': '86400', 17:27:27 INFO - 'USER': 'cltbld', 17:27:27 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1486690023.617762-1245543442', 17:27:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:27:27 INFO - '_': '/tools/buildbot/bin/python'} 17:27:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:27:27 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:27:27 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 17:27:27 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 17:27:27 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 17:27:27 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 17:27:27 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 17:27:27 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 17:27:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:27:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:27:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:27:29 INFO - Installing collected packages: mozsystemmonitor 17:27:29 INFO - Running setup.py install for mozsystemmonitor 17:27:29 INFO - Successfully installed mozsystemmonitor 17:27:29 INFO - Cleaning up... 17:27:29 INFO - Return code: 0 17:27:29 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:27:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:27:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:27:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:27:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:27:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:27:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:27:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x13681f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1535a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16da980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16d7d00>, '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 0x16d8a00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16d8eb0>, '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': '199fb4c72b90437b990b419b000001c5-1486690023.617762-1245543442', '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 17:27:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 17:27:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 17:27:29 INFO - Using env: {'DISPLAY': ':0', 17:27:29 INFO - 'HOME': '/home/cltbld', 17:27:29 INFO - 'LANG': 'en_US.UTF-8', 17:27:29 INFO - 'LANGUAGE': 'en_US:en', 17:27:29 INFO - 'LOGNAME': 'cltbld', 17:27:29 INFO - 'MAIL': '/var/mail/cltbld', 17:27:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:27:29 INFO - 'MOZ_NO_REMOTE': '1', 17:27:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:27:29 INFO - 'NO_EM_RESTART': '1', 17:27:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:27:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:27:29 INFO - 'PWD': '/builds/slave/test', 17:27:29 INFO - 'SHELL': '/bin/bash', 17:27:29 INFO - 'SHLVL': '1', 17:27:29 INFO - 'TERM': 'linux', 17:27:29 INFO - 'TMOUT': '86400', 17:27:29 INFO - 'USER': 'cltbld', 17:27:29 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1486690023.617762-1245543442', 17:27:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:27:29 INFO - '_': '/tools/buildbot/bin/python'} 17:27:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:27:30 INFO - Downloading/unpacking jsonschema==2.5.1 17:27:30 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 17:27:30 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 17:27:30 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 17:27:30 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 17:27:30 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 17:27:30 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 17:27:32 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:27:32 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:27: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 17:27: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 17:27: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 17:27: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 17:27: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 17:27: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 17:27:32 INFO - Downloading functools32-3.2.3-2.tar.gz 17:27:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:27:32 INFO - warning: no files found matching '*.txt' 17:27:32 INFO - no previously-included directories found matching 'build' 17:27:32 INFO - no previously-included directories found matching 'dist' 17:27:32 INFO - no previously-included directories found matching '.git*' 17:27:32 INFO - Installing collected packages: jsonschema, functools32 17:27:32 INFO - Running setup.py install for functools32 17:27:32 INFO - warning: no files found matching '*.txt' 17:27:32 INFO - no previously-included directories found matching 'build' 17:27:32 INFO - no previously-included directories found matching 'dist' 17:27:32 INFO - no previously-included directories found matching '.git*' 17:27:32 INFO - Successfully installed jsonschema functools32 17:27:32 INFO - Cleaning up... 17:27:32 INFO - Return code: 0 17:27:32 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:27:32 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')]} 17:27:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:27:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:27:32 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')]} 17:27:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:27:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:27:32 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 0x13681f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1535a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16da980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16d7d00>, '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 0x16d8a00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16d8eb0>, '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': '199fb4c72b90437b990b419b000001c5-1486690023.617762-1245543442', '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 17:27:32 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 17:27:32 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 17:27:32 INFO - Using env: {'DISPLAY': ':0', 17:27:32 INFO - 'HOME': '/home/cltbld', 17:27:32 INFO - 'LANG': 'en_US.UTF-8', 17:27:32 INFO - 'LANGUAGE': 'en_US:en', 17:27:32 INFO - 'LOGNAME': 'cltbld', 17:27:32 INFO - 'MAIL': '/var/mail/cltbld', 17:27:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:27:32 INFO - 'MOZ_NO_REMOTE': '1', 17:27:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:27:32 INFO - 'NO_EM_RESTART': '1', 17:27:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:27:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:27:32 INFO - 'PWD': '/builds/slave/test', 17:27:32 INFO - 'SHELL': '/bin/bash', 17:27:32 INFO - 'SHLVL': '1', 17:27:32 INFO - 'TERM': 'linux', 17:27:32 INFO - 'TMOUT': '86400', 17:27:32 INFO - 'USER': 'cltbld', 17:27:32 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1486690023.617762-1245543442', 17:27:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:27:32 INFO - '_': '/tools/buildbot/bin/python'} 17:27:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:27:32 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:27:32 INFO - Cleaning up... 17:27:32 INFO - Return code: 0 17:27:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:27:32 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')]} 17:27:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:27:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:27:32 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')]} 17:27:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:27:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:27:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x13681f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1535a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16da980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16d7d00>, '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 0x16d8a00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16d8eb0>, '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': '199fb4c72b90437b990b419b000001c5-1486690023.617762-1245543442', '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 17:27:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:27:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 17:27:33 INFO - Using env: {'DISPLAY': ':0', 17:27:33 INFO - 'HOME': '/home/cltbld', 17:27:33 INFO - 'LANG': 'en_US.UTF-8', 17:27:33 INFO - 'LANGUAGE': 'en_US:en', 17:27:33 INFO - 'LOGNAME': 'cltbld', 17:27:33 INFO - 'MAIL': '/var/mail/cltbld', 17:27:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:27:33 INFO - 'MOZ_NO_REMOTE': '1', 17:27:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:27:33 INFO - 'NO_EM_RESTART': '1', 17:27:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:27:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:27:33 INFO - 'PWD': '/builds/slave/test', 17:27:33 INFO - 'SHELL': '/bin/bash', 17:27:33 INFO - 'SHLVL': '1', 17:27:33 INFO - 'TERM': 'linux', 17:27:33 INFO - 'TMOUT': '86400', 17:27:33 INFO - 'USER': 'cltbld', 17:27:33 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1486690023.617762-1245543442', 17:27:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:27:33 INFO - '_': '/tools/buildbot/bin/python'} 17:27:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:27:33 INFO - Downloading/unpacking blobuploader==1.2.4 17:27:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:27:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:27:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:27:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:27:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:27:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:27:35 INFO - Downloading blobuploader-1.2.4.tar.gz 17:27:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:27:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:27:35 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 17:27:35 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 17:27:35 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 17:27:35 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 17:27:35 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 17:27:35 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 17:27:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:27:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:27:35 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 17:27:35 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 17:27:35 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 17:27:35 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 17:27:35 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 17:27:35 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 17:27:36 INFO - Downloading docopt-0.6.1.tar.gz 17:27:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:27:36 INFO - Installing collected packages: blobuploader, requests, docopt 17:27:36 INFO - Running setup.py install for blobuploader 17:27:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:27:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:27:36 INFO - Running setup.py install for requests 17:27:36 INFO - Running setup.py install for docopt 17:27:36 INFO - Successfully installed blobuploader requests docopt 17:27:36 INFO - Cleaning up... 17:27:36 INFO - Return code: 0 17:27:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:27:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:27:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:27:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:27:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:27:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:27:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:27:36 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 0x13681f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1535a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16da980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16d7d00>, '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 0x16d8a00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16d8eb0>, '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': '199fb4c72b90437b990b419b000001c5-1486690023.617762-1245543442', '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 17:27:36 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 17:27:36 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 17:27:36 INFO - Using env: {'DISPLAY': ':0', 17:27:36 INFO - 'HOME': '/home/cltbld', 17:27:36 INFO - 'LANG': 'en_US.UTF-8', 17:27:36 INFO - 'LANGUAGE': 'en_US:en', 17:27:36 INFO - 'LOGNAME': 'cltbld', 17:27:36 INFO - 'MAIL': '/var/mail/cltbld', 17:27:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:27:36 INFO - 'MOZ_NO_REMOTE': '1', 17:27:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:27:36 INFO - 'NO_EM_RESTART': '1', 17:27:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:27:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:27:36 INFO - 'PWD': '/builds/slave/test', 17:27:36 INFO - 'SHELL': '/bin/bash', 17:27:36 INFO - 'SHLVL': '1', 17:27:36 INFO - 'TERM': 'linux', 17:27:36 INFO - 'TMOUT': '86400', 17:27:36 INFO - 'USER': 'cltbld', 17:27:36 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1486690023.617762-1245543442', 17:27:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:27:36 INFO - '_': '/tools/buildbot/bin/python'} 17:27:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:27:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:27:37 INFO - Running setup.py (path:/tmp/pip-emSc2B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:27:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:27:37 INFO - Running setup.py (path:/tmp/pip-BE17LC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:27:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:27:37 INFO - Running setup.py (path:/tmp/pip-2dUopE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:27:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:27:37 INFO - Running setup.py (path:/tmp/pip-0xu51d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:27:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:27:37 INFO - Running setup.py (path:/tmp/pip-uxP30P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:27:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:27:37 INFO - Running setup.py (path:/tmp/pip-jvYLu0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:27:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:27:37 INFO - Running setup.py (path:/tmp/pip-GhrLSY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:27:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:27:37 INFO - Running setup.py (path:/tmp/pip-vYN9p6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:27:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:27:37 INFO - Running setup.py (path:/tmp/pip-UOWS5U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:27:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:27:37 INFO - Running setup.py (path:/tmp/pip-crRRRT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:27:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:27:37 INFO - Running setup.py (path:/tmp/pip-kB_C7a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:27:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:27:37 INFO - Running setup.py (path:/tmp/pip-NTwXIb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:27:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:27:37 INFO - Running setup.py (path:/tmp/pip-X3xpL5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:27:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:27:37 INFO - Running setup.py (path:/tmp/pip-4k5Eym-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:27:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:27:38 INFO - Running setup.py (path:/tmp/pip-VgBMc2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:27:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:27:38 INFO - Running setup.py (path:/tmp/pip-RrzCT9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:27:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:27:38 INFO - Running setup.py (path:/tmp/pip-Ib66mE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:27:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:27:38 INFO - Running setup.py install for manifestparser 17:27:38 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:27:38 INFO - Running setup.py install for mozcrash 17:27:38 INFO - Running setup.py install for mozdebug 17:27:38 INFO - Running setup.py install for mozdevice 17:27:38 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:27:38 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:27:38 INFO - Running setup.py install for mozfile 17:27:38 INFO - Running setup.py install for mozhttpd 17:27:38 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:27:38 INFO - Running setup.py install for mozinfo 17:27:38 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:27:38 INFO - Running setup.py install for mozInstall 17:27:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:27:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:27:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:27:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:27:39 INFO - Running setup.py install for mozleak 17:27:39 INFO - Running setup.py install for mozlog 17:27:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:27:39 INFO - Running setup.py install for moznetwork 17:27:39 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:27:39 INFO - Running setup.py install for mozprocess 17:27:39 INFO - Running setup.py install for mozprofile 17:27:39 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:27:39 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:27:39 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:27:39 INFO - Running setup.py install for mozrunner 17:27:39 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:27:39 INFO - Running setup.py install for mozscreenshot 17:27:39 INFO - Running setup.py install for moztest 17:27:39 INFO - Running setup.py install for mozversion 17:27:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:27:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:27:40 INFO - Cleaning up... 17:27:40 INFO - Return code: 0 17:27:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:27: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')]} 17:27:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:27:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:27: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')]} 17:27:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:27:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:27:40 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 0x13681f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1535a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16da980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16d7d00>, '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 0x16d8a00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16d8eb0>, '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': '199fb4c72b90437b990b419b000001c5-1486690023.617762-1245543442', '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 17:27:40 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 17:27:40 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 17:27:40 INFO - Using env: {'DISPLAY': ':0', 17:27:40 INFO - 'HOME': '/home/cltbld', 17:27:40 INFO - 'LANG': 'en_US.UTF-8', 17:27:40 INFO - 'LANGUAGE': 'en_US:en', 17:27:40 INFO - 'LOGNAME': 'cltbld', 17:27:40 INFO - 'MAIL': '/var/mail/cltbld', 17:27:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:27:40 INFO - 'MOZ_NO_REMOTE': '1', 17:27:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:27:40 INFO - 'NO_EM_RESTART': '1', 17:27:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:27:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:27:40 INFO - 'PWD': '/builds/slave/test', 17:27:40 INFO - 'SHELL': '/bin/bash', 17:27:40 INFO - 'SHLVL': '1', 17:27:40 INFO - 'TERM': 'linux', 17:27:40 INFO - 'TMOUT': '86400', 17:27:40 INFO - 'USER': 'cltbld', 17:27:40 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1486690023.617762-1245543442', 17:27:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:27:40 INFO - '_': '/tools/buildbot/bin/python'} 17:27:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:27:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:27:40 INFO - Running setup.py (path:/tmp/pip-FwAaQ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:27:40 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)) 17:27:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:27:40 INFO - Running setup.py (path:/tmp/pip-bUN5ZZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:27:40 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)) 17:27:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:27:40 INFO - Running setup.py (path:/tmp/pip-R_H3Pt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:27:40 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)) 17:27:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:27:40 INFO - Running setup.py (path:/tmp/pip-NACZdw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:27:40 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)) 17:27:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:27:40 INFO - Running setup.py (path:/tmp/pip-JEfFjB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:27:40 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)) 17:27:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:27:40 INFO - Running setup.py (path:/tmp/pip-BqfI5F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:27:40 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)) 17:27:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:27:40 INFO - Running setup.py (path:/tmp/pip-G9e214-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:27:40 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)) 17:27:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:27:40 INFO - Running setup.py (path:/tmp/pip-kj6aTQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:27:40 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)) 17:27:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:27:40 INFO - Running setup.py (path:/tmp/pip-TIo9WP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:27:40 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)) 17:27:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:27:40 INFO - Running setup.py (path:/tmp/pip-J3FkvF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:27:41 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)) 17:27:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:27:41 INFO - Running setup.py (path:/tmp/pip-2C7aHg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:27:41 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)) 17:27:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:27:41 INFO - Running setup.py (path:/tmp/pip-m7izS2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:27:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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)) 17:27:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:27:41 INFO - Running setup.py (path:/tmp/pip-C6PBc2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:27:41 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)) 17:27:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:27:41 INFO - Running setup.py (path:/tmp/pip-1ETmEL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:27:41 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)) 17:27:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:27:41 INFO - Running setup.py (path:/tmp/pip-nBvi50-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:27:41 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)) 17:27:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:27:41 INFO - Running setup.py (path:/tmp/pip-D5sPGf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:27:41 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)) 17:27:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:27:41 INFO - Running setup.py (path:/tmp/pip-QNfsWU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:27:41 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)) 17:27:41 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)) 17:27:41 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)) 17:27:41 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)) 17:27:41 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)) 17:27:41 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)) 17:27:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:27:41 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 17:27:41 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 17:27:41 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 17:27:41 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 17:27:41 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 17:27:41 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 17:27:43 INFO - Downloading blessings-1.6.tar.gz 17:27:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:27:43 INFO - Installing collected packages: blessings 17:27:43 INFO - Running setup.py install for blessings 17:27:43 INFO - Successfully installed blessings 17:27:43 INFO - Cleaning up... 17:27:43 INFO - Return code: 0 17:27:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:27:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:27:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:27:44 INFO - Reading from file tmpfile_stdout 17:27:44 INFO - Current package versions: 17:27:44 INFO - argparse == 1.2.1 17:27:44 INFO - blessings == 1.6 17:27:44 INFO - blobuploader == 1.2.4 17:27:44 INFO - docopt == 0.6.1 17:27:44 INFO - functools32 == 3.2.3-2 17:27:44 INFO - jsonschema == 2.5.1 17:27:44 INFO - manifestparser == 1.1 17:27:44 INFO - mozInstall == 1.12 17:27:44 INFO - mozcrash == 1.0 17:27:44 INFO - mozdebug == 0.1 17:27:44 INFO - mozdevice == 0.49 17:27:44 INFO - mozfile == 1.2 17:27:44 INFO - mozhttpd == 0.7 17:27:44 INFO - mozinfo == 0.9 17:27:44 INFO - mozleak == 0.1 17:27:44 INFO - mozlog == 3.4 17:27:44 INFO - moznetwork == 0.27 17:27:44 INFO - mozprocess == 0.24 17:27:44 INFO - mozprofile == 0.28 17:27:44 INFO - mozrunner == 6.13 17:27:44 INFO - mozscreenshot == 0.1 17:27:44 INFO - mozsystemmonitor == 0.3 17:27:44 INFO - moztest == 0.8 17:27:44 INFO - mozversion == 1.4 17:27:44 INFO - psutil == 3.1.1 17:27:44 INFO - requests == 1.2.3 17:27:44 INFO - wsgiref == 0.1.2 17:27:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:27: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')]} 17:27:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:27:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:27: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')]} 17:27:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:27:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:27:44 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 0x13681f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1535a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16da980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16d7d00>, '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 0x16d8a00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16d8eb0>, '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': '199fb4c72b90437b990b419b000001c5-1486690023.617762-1245543442', '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 17:27:44 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 17:27:44 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 17:27:44 INFO - Using env: {'DISPLAY': ':0', 17:27:44 INFO - 'HOME': '/home/cltbld', 17:27:44 INFO - 'LANG': 'en_US.UTF-8', 17:27:44 INFO - 'LANGUAGE': 'en_US:en', 17:27:44 INFO - 'LOGNAME': 'cltbld', 17:27:44 INFO - 'MAIL': '/var/mail/cltbld', 17:27:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:27:44 INFO - 'MOZ_NO_REMOTE': '1', 17:27:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:27:44 INFO - 'NO_EM_RESTART': '1', 17:27:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:27:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:27:44 INFO - 'PWD': '/builds/slave/test', 17:27:44 INFO - 'SHELL': '/bin/bash', 17:27:44 INFO - 'SHLVL': '1', 17:27:44 INFO - 'TERM': 'linux', 17:27:44 INFO - 'TMOUT': '86400', 17:27:44 INFO - 'USER': 'cltbld', 17:27:44 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1486690023.617762-1245543442', 17:27:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:27:44 INFO - '_': '/tools/buildbot/bin/python'} 17:27:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:27:44 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)) 17:27:44 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)) 17:27:44 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)) 17:27:44 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)) 17:27:44 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)) 17:27:44 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)) 17:27:44 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)) 17:27:44 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)) 17:27:44 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)) 17:27:44 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)) 17:27:44 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)) 17:27:44 INFO - Cleaning up... 17:27:44 INFO - Return code: 0 17:27:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:27: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')]} 17:27:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:27:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:27: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')]} 17:27:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:27:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:27:44 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 0x13681f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1535a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16da980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16d7d00>, '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 0x16d8a00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16d8eb0>, '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': '199fb4c72b90437b990b419b000001c5-1486690023.617762-1245543442', '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 17:27:44 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 17:27:44 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 17:27:44 INFO - Using env: {'DISPLAY': ':0', 17:27:44 INFO - 'HOME': '/home/cltbld', 17:27:44 INFO - 'LANG': 'en_US.UTF-8', 17:27:44 INFO - 'LANGUAGE': 'en_US:en', 17:27:44 INFO - 'LOGNAME': 'cltbld', 17:27:44 INFO - 'MAIL': '/var/mail/cltbld', 17:27:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:27:44 INFO - 'MOZ_NO_REMOTE': '1', 17:27:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:27:44 INFO - 'NO_EM_RESTART': '1', 17:27:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:27:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:27:44 INFO - 'PWD': '/builds/slave/test', 17:27:44 INFO - 'SHELL': '/bin/bash', 17:27:44 INFO - 'SHLVL': '1', 17:27:44 INFO - 'TERM': 'linux', 17:27:44 INFO - 'TMOUT': '86400', 17:27:44 INFO - 'USER': 'cltbld', 17:27:44 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1486690023.617762-1245543442', 17:27:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:27:44 INFO - '_': '/tools/buildbot/bin/python'} 17:27:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:27:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:27:44 INFO - Cleaning up... 17:27:44 INFO - Return code: 0 17:27:44 INFO - Running post-action listener: _resource_record_post_action 17:27:44 INFO - Running post-action listener: _start_resource_monitoring 17:27:44 INFO - Starting resource monitoring. 17:27:44 INFO - [mozharness: 2017-02-10 01:27:44.545009Z] Finished create-virtualenv step (success) 17:27:44 INFO - [mozharness: 2017-02-10 01:27:44.545500Z] Running install step. 17:27:44 INFO - Running pre-action listener: _resource_record_pre_action 17:27:44 INFO - Running main action method: install 17:27:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:27:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:27:44 INFO - Reading from file tmpfile_stdout 17:27:44 INFO - Detecting whether we're running mozinstall >=1.0... 17:27:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:27:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:27:44 INFO - Reading from file tmpfile_stdout 17:27:44 INFO - Output received: 17:27:44 INFO - Usage: mozinstall [options] installer 17:27:44 INFO - Options: 17:27:44 INFO - -h, --help show this help message and exit 17:27:44 INFO - -d DEST, --destination=DEST 17:27:44 INFO - Directory to install application into. [default: 17:27:44 INFO - "/builds/slave/test"] 17:27:44 INFO - --app=APP Application being installed. [default: firefox] 17:27:44 INFO - mkdir: /builds/slave/test/build/application 17:27:44 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'] 17:27:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 17:28:01 INFO - Reading from file tmpfile_stdout 17:28:01 INFO - Output received: 17:28:01 INFO - /builds/slave/test/build/application/firefox/firefox 17:28:01 INFO - Running post-action listener: _resource_record_post_action 17:28:01 INFO - [mozharness: 2017-02-10 01:28:01.272934Z] Finished install step (success) 17:28:01 INFO - [mozharness: 2017-02-10 01:28:01.273286Z] Running run-tests step. 17:28:01 INFO - Running pre-action listener: _resource_record_pre_action 17:28:01 INFO - Running main action method: run_tests 17:28:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:28:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:28:01 INFO - Python 2.7.3 17:28:01 INFO - Return code: 0 17:28:01 INFO - grabbing minidump binary from tooltool 17:28:01 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')]} 17:28:01 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 0x16d7d00>, '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 0x16d8a00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16d8eb0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:28:01 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 17:28:01 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 17:28:01 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 17:28:01 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:28:01 INFO - Return code: 0 17:28:01 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:28:01 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:28:01 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:28:01 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:28:01 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:28:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Tn4IGwBxS7669Hnno10MvQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-072', '--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 17:28:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Tn4IGwBxS7669Hnno10MvQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-072 --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 17:28:01 INFO - Using env: {'DISPLAY': ':0', 17:28:01 INFO - 'HOME': '/home/cltbld', 17:28:01 INFO - 'LANG': 'en_US.UTF-8', 17:28:01 INFO - 'LANGUAGE': 'en_US:en', 17:28:01 INFO - 'LOGNAME': 'cltbld', 17:28:01 INFO - 'MAIL': '/var/mail/cltbld', 17:28:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:28:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:28:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:28:01 INFO - 'MOZ_NO_REMOTE': '1', 17:28:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:28:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:28:01 INFO - 'NO_EM_RESTART': '1', 17:28:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:28:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:28:01 INFO - 'PWD': '/builds/slave/test', 17:28:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:28:01 INFO - 'SHELL': '/bin/bash', 17:28:01 INFO - 'SHLVL': '1', 17:28:01 INFO - 'TERM': 'linux', 17:28:01 INFO - 'TMOUT': '86400', 17:28:01 INFO - 'USER': 'cltbld', 17:28:01 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1486690023.617762-1245543442', 17:28:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:28:01 INFO - '_': '/tools/buildbot/bin/python'} 17:28:01 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Tn4IGwBxS7669Hnno10MvQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-072', '--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 17:28:01 INFO - mozversion application_buildid: 20170210002235 17:28:01 INFO - mozversion application_changeset: 63752d3df9fa09ecd3283eaccb950cc5607fbab1 17:28:01 INFO - mozversion application_display_name: FirefoxDeveloperEdition 17:28:01 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:28:01 INFO - mozversion application_name: Firefox 17:28:01 INFO - mozversion application_remotingname: firefox-dev 17:28:01 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 17:28:01 INFO - mozversion application_vendor: Mozilla 17:28:01 INFO - mozversion application_version: 53.0a2 17:28:01 INFO - mozversion platform_buildid: 20170210002235 17:28:01 INFO - mozversion platform_changeset: 63752d3df9fa09ecd3283eaccb950cc5607fbab1 17:28:01 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 17:28:01 INFO - mozversion platform_version: 53.0a2 17:28:01 INFO - using testdate: 1486690081 17:28:01 INFO - actual date: 1486690081 17:28:01 INFO - starting webserver on 'localhost:38934' 17:28:01 INFO - SUITE-START | Running 2 tests 17:28:01 INFO - TEST-START | dromaeo_css 17:28:01 INFO - operating with platform_type : linux_ 17:28:01 INFO - Initialising browser for dromaeo_css test... 17:28:01 INFO - TEST-INFO | started process 20462 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFNs2Yi/profile http://localhost:38934/getInfo.html) 17:28:05 INFO - PROCESS | 20462 | __metrics Screen width/height:1600/1200 17:28:05 INFO - PROCESS | 20462 | colorDepth:24 17:28:05 INFO - PROCESS | 20462 | Browser inner width/height: 1024/697 17:28:05 INFO - PROCESS | 20462 | __metrics 17:28:06 INFO - PROCESS | 20462 | 1486690086399 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 17:28:06 INFO - PROCESS | 20462 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:28:06 INFO - PROCESS | 20462 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:28:07 INFO - TEST-INFO | 20462: exit 0 17:28:07 INFO - Browser initialized. 17:28:07 INFO - Running cycle 1/1 for dromaeo_css test... 17:28:07 INFO - TEST-INFO | started process 20555 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFNs2Yi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:28:19 INFO - PROCESS | 20555 | RSS: Main: 138018816 17:28:19 INFO - PROCESS | 20555 | 17:31:19 INFO - PROCESS | 20555 | Cycle 1(1): loaded http://localhost:38934/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38934/tests/dromaeo/cssquery-ext.html) 17:31:21 INFO - PROCESS | 20555 | RSS: Main: 246628352 17:31:21 INFO - PROCESS | 20555 | 17:33:09 INFO - PROCESS | 20555 | ************************* 17:33:09 INFO - PROCESS | 20555 | A coding exception was thrown and uncaught in a Task. 17:33:09 INFO - PROCESS | 20555 | 17:33:09 ERROR - PROCESS | 20555 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:33:09 INFO - PROCESS | 20555 | Full stack: 17:33:09 INFO - PROCESS | 20555 | ************************* 17:34:23 INFO - PROCESS | 20555 | Cycle 1(1): loaded http://localhost:38934/tests/dromaeo/cssquery-ext.html (next: http://localhost:38934/tests/dromaeo/cssquery-jquery.html) 17:34:23 INFO - PROCESS | 20555 | RSS: Main: 217960448 17:34:23 INFO - PROCESS | 20555 | 17:37:25 INFO - PROCESS | 20555 | Cycle 1(1): loaded http://localhost:38934/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38934/tests/dromaeo/cssquery-mootools.html) 17:37:26 INFO - PROCESS | 20555 | RSS: Main: 177491968 17:37:26 INFO - PROCESS | 20555 | 17:40:28 INFO - PROCESS | 20555 | Cycle 1(1): loaded http://localhost:38934/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38934/tests/dromaeo/cssquery-prototype.html) 17:40:28 INFO - PROCESS | 20555 | RSS: Main: 197955584 17:40:28 INFO - PROCESS | 20555 | 17:43:31 INFO - PROCESS | 20555 | Cycle 1(1): loaded http://localhost:38934/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38934/tests/dromaeo/cssquery-yui.html) 17:43:32 INFO - PROCESS | 20555 | RSS: Main: 170524672 17:43:32 INFO - PROCESS | 20555 | 17:46:34 INFO - PROCESS | 20555 | Cycle 1(1): loaded http://localhost:38934/tests/dromaeo/cssquery-yui.html (next: http://localhost:38934/tests/dromaeo/cssquery-dojo.html) 17:46:34 INFO - PROCESS | 20555 | RSS: Main: 267042816 17:46:34 INFO - PROCESS | 20555 | 17:46:34 INFO - PROCESS | 20555 | __start_tp_report 17:46:34 INFO - PROCESS | 20555 | _x_x_mozilla_page_load 17:46:34 INFO - PROCESS | 20555 | _x_x_mozilla_page_load_details 17:46:34 INFO - PROCESS | 20555 | |i|pagename|runs| 17:46:34 INFO - PROCESS | 20555 | |0;dojo.html;3295.258566822465;3320.2031512437006;3321.568196134502;3352.4971254311868;3375.257443362461;5478.890422191554;5554.361248456424;5577.358603760569;5584.301962254718;5595.048841696911;5571.359293681227;5637.210790489335;5637.830865074055;5675.51758100562;5682;5087.694738315695;5114.488551144875;5121.564667003309;5158.8710282243055;5187.19598462239;5000.799968001294;5018.34761481007;5197.298364720752;5197.740112994354;5225.712585807779;5027.069992051483;5048.570871475929;5070.163423035195;5124.256982737501;5141.794328226866;5156.381234251895;5156.767945442427;5158.948410515922;5163.535281824654;5188.040212560651;4887.70673759572;5055.292259083732;5072.923906141411;5132.74336283188;5133.563647090002;5435.211894275348;5465.672059676393;5513.724313784295;5534.889302213974;5598.104303311491;5967.970160149215;5986.610870293461;6011.248593925759;6016.608920420159;6021.849453763647;6061.969690151521;6070.696465176724;6073.908891366633;6084.117802918597;6088.66512341821;5397.4062853086125;5418.241446197536;5436.103042997861;5444.754986025599;5674.631148975344;5936.495397891142;5950.553708471795;5953.166556682068;5953.523718102541;5994.1608174855555;5890.705464726832;5923.289205295392;5946.851328716817;5949.762009519657;5952.987992041363;5810.709464526757;5859.794907178229;5884.058550631964;6032.547558933097;6041.969790151021;5924.6149000314845;5957.195778569838;5997.250343707036;6026.216591843032;6031.215941927521;5984.222051133412;5989.520838332923;5991.58058935879;5997.250343707036;5997.970010150008;4468.865934021985;4511.142882852248;4615.446146462379;4644.024754801462;4651.465081515601;3938.4879965604287;4032.858849940238;4084.0606660467665;4128.545859955402;4140.91718165635;5726.770929162787;5727.3699893011735;5728.369879313273;5729.226554415184;5729.369769325371;5835.037218858841;5874.882502349929;5897.49871260939;5986.102084687332;6049.848753781191;5614.831555053355;5662.207290979266;5701.087825947829;5702.258706368146;5720.170575266563;5062.645614806928;5072.315237442972;5074.5686616637995;5076.111680455905;5102.311187989574;6105.45050945417;6116.731440059295;6123.099607468126;6250.593711408744;6269.498440124779;5963.642181469126;6030.1256317832795;6035.849103772441;6057.091436284592;6075.787347442818;6228.096925945803;6252.562320637512;6260.624362538262;6270.717817698123;6272.968635156795;5685.0335442973965;5777.133429985536;5939.376365481562;5943.435373639497;5984.162217289496;5906.232189815297;5929.495992840657;5964.433378829004;6023.909641355471;6060.1212824139375;5835.56233282497;5892.204552385373;5905.881882362242;5906.527477801851;5907.616004959664;5602.327720673382;5896.823095307148;5900.232969713909;5904.468597826216;5909.054551271776;5703.230063941315;5762.30852297727;5826.359100498857;5831.504322132666;5916.526677865674;5878.559108066826;5932;5933.970330148494;5939.851503712442;5944.8811023780145;5813.418658134153;5815.098659707752;5816.709164541671;5821.7089145543405;5823.5923485355615 17:46:34 INFO - PROCESS | 20555 | |1;ext.html;544.3541515524804;591.5592883301939;613.0926229180812;615.5014438304974;636.6880228687938;36879.81560092199;47875.76062119693;49120.50879491204;49125.7543712281;49134.26298605524;50834.74582627082;51645.483545164534;51728.74135629317;51740.74129629356;51778.741106294416;8460.36547258955;8646;9240.722778316662;9489.383190092622;11195.160362972809;10896.18278629102;11319.943400282988;11322;11328.716782080473;11335.37655428951;11252;11338.716532086723;11340.319580825177;11350.262232954832;11394.658160255205;11293.322400655947;11351.602693905674;11367.658970230907;11377.715557111138;11380.317180969168;11183.496742646601;11265.155113366449;11274.09807215429;11279.774404511945;11282.210245282833;46559.76720116378;46996.53003469956;47079.29381059287;47144;47193.29210061852;4336.783160841946;4366.8034938427845;4416.668749843742;4481.395011673448;4538.251188553884;33748;34008;34356.31287374238;34442.65557344445;35586.466203006974;4326.242907491176;4335.479742430897;4369.213541562517;4379.124175164948;4466.508684044752;361761;363009;363321;364335.17832410935;365444;67921;67989;68153.65923170451;68301.31698683048;68391;71788.2821171792;71827;71841.28158718346;71869.28130718731;71967.6401617999;19649.607007859762;20138.39584812458;20286.898565507374;20296.59406811885;20307.593848122953;14140.50508232207;14230.430782768573;14464.059836110619;14743.63140921464;14815.777763333559;19592.804071959097;19961.900190498956;19973.900130499256;19982;20051;6493.675316234169;6521.478281737544;6549.410553050153;6713.4964877634375;6738.528303018839;965.4786415335781;990.4997976022207;993.3592832623062;995.5470261031213;1003.0721582536125;32951.835240823646;33462.832685836416;33608.1597960053;33931.15172120717;34396.82801585976;1059.957601695939;1173.6244401791516;1176.5587904535798;1195.7847587434346;1215.0522592377959;17415;17714.291428342978;17721.7341739874;18012;18060.27758889656;472587;473451;476309;477070;477735;58980.41019589749;59192.704036480405;59442;59442;59576.70211648914;1494.3873012065;1495.5438591229563;1531.1349087765377;1541.576066581699;1561.6408226108058;30064;30944.845275773478;31446.371072578877;31486.770222801806;31975.840120799247;49273.26090108723;49935.75032124816;49945.25082123699;50016;50020.24969625531;378558;380309;380816;380904;381262;25934;26416.207513774618;27378.315542111606;27550.44899102047;28414.57378139371;44872;45346;45379.773101134284;45637.08725825398;45642;37571.624283757905;37709.622903771706;37906.43140352952;38916.610833891296;39411.21177576375;36688.05713001552;36745;37703;37964.62035379611;37973.81013094917;37832.810835946744;38364.424533631456;38420.6157938417;38679.80660096682;39267;31323.686763132988;33411;33557.328853423285;33561.49657755184;33584;25754.484910302064;25965.74034259633;26019.869900650374;26300.605490917267;26943.595846062715 17:46:34 INFO - PROCESS | 20555 | |2;jquery.html;424.255431717336;426.84846879357815;429.6906227516189;432.87879393769714;450.51794579799605;19858.304959326408;20331.28840490583;21437.249696260595;21635.783642163573;21753.803540805267;19446.805531944676;20515.897420512876;20578.279760208396;20682.68975965362;21093;5961.61249518781;6204.596701214429;6212.65830379329;6217.875642487147;6219.564630475868;5971;6015.518758499332;6070.210872586557;6093.756249750027;6093.908591371111;6063.848403789896;6066.695660433006;6067.696615169246;6076.453119219269;6078.240219972504;5848.941510584904;6039.667818269993;6069.119977603267;6085.726142323606;6088.299845517734;5245.134552798783;5393.1101368274185;5418.945810541923;5428.511433970962;5444.8366549003595;24928.12751553705;25349.873250633813;25392.36519087019;25436.23691289246;25650.615240771403;3231.515272709089;3275.5250488679008;3300.620428650712;3348.2299071213515;3349.681780230874;15077.849221507864;15560.766588501181;15670.451534196362;16015.919920400323;16282.918585406996;2008.066249194117;2024.6153230886232;2034.511717187879;2035.986739625348;2051.938441846747;83056.16943830544;83723.5813820927;84430;85176.14823851745;87785.56107219488;11422.200445968869;11563.190576659554;11603.941980290045;11647.30116193031;11752.294862308288;14997;15098.622534436727;15174.696506069817;15192.620184495256;15231.238438078051;8843.557822108869;9065;9116.1339672731;9166.954165229132;9199.172074513326;8869;8896.199342059246;8905.064968178378;9138.17756401927;9159.95420022909;7117.572945623279;7297.3432391084;7348.265173482716;7372.594507302043;7449.851002980018;2033.2070492507933;2054.5274586845107;2062.989685051565;2092.204962114387;2099.5695882344085;946.3564775952281;949.7293271417612;951.8678160919628;958.8274110660009;971.1308379000754;19795.802041979397;21482;21501.064904158702;21562.568748625254;21721.87284415956;976.8046390721884;979.8481235408523;989.391524212604;993.304686719285;994.2096033653263;11252.455893151297;12027.398630068461;12086.637400877988;12109;12189.390530473442;553084;610533;611827;623591.2110980165;629636;84716;84834;86526;87244.56377718071;88668;1147.1511081799574;1179.3572502985894;1182.9467673954694;1184.4196343791652;1196.7726132034886;17055.573610659834;17847.643047138725;18168.45494635163;18442.170102345157;18505.96434392189;39984;40428.1914361717;40852.18295634011;40887.795561022;41271.58728412678;51081.74459127681;52201;52778.73610631922;53771.19343209933;54242.45757542374;8472.279856212044;8515;8889.822203556021;9045.764603745503;9203.493807840365;8345.374096942633;12044.879551204376;13061.804072939103;13136.211827290394;13992.300384980506;10256.28206025601;10539.262251642618;10678.199135064746;10727.356358618508;10980.890191097988;10535.736606584896;11083.279586826831;11163.44182790841;11251.49368278413;11366.090712743126;11348.489317980546;11353.886461135613;11399.20205585601;11406.372649504357;11535.884641153481;8785.165409286108;8794.824103518022;8840.513771742615;9002.81994360096;9010.966119600544;10767.946160269148;10802.351858888493;11076;11095.667129986114;11428 17:46:34 INFO - PROCESS | 20555 | |3;mootools.html;1381.8479967203612;1492.51493264689;1493.9626509337272;1520.0575643101276;1526.473366688492;7971.282584567388;8816.338774591899;9078.500682462462;9092.727218183456;9329.720108396741;9112.635494580209;9163.49600771959;9171.174594286513;9185.954070229656;9201.21789647879;2046.7748547659746;2115.375964090597;2365.1367250953363;2402.0752010476003;2402.723686776017;2371.7509661485556;2425.0542288507495;2440.255722004788;2447.278052974377;2457.950840983179;2337.7662233776655;2353.505763789606;2370.8696021718797;2371.087131454385;2376.0733314007553;2344.0740907341506;2368.3842201027683;2389.9402514937265;2393.5093305872256;2396.8681722505344;2301.148575027234;2360.468894498741;2363.137454828975;2364.6807680963193;2364.7635236476385;9070.773230669287;9134.862977055349;9138.223251023672;9141.634334626608;9145.039770824034;5363.839084827461;5481.068218402881;5485.6982865942355;5486.588505862076;5493.450654934514;3353.279045005336;3492.879707415265;3545.716342692604;3598.712103031751;3623.9456408153897;1401.8317801863739;1460.7005563859384;1479.1494890437934;1531.5252271795669;1538.8461153884634;11925.403729813475;12530.498780048878;12900.80648790269;12913.741725165444;12919.41862616175;4518.299663552155;4655.767211639472;4770.427548694179;4775.832845850378;4800.4719480857075;10169.54237059334;10169.9491502542;10182.847257291147;10183.7454063649;10184.796304073878;5533.22534845122;5540.252065971123;5545;5546.805861794818;5553.916691249635;4524.615407690383;4738.502457242009;5031.245313203049;5162.819301324435;5228.163493840967;4023.376376661622;4053.3514637658423;4097.057676734308;4120.299549076664;4133.669306455537;1152.6138743521014;1153.26191237608;1154.2901115813713;1157.8494795676509;1171.4377098992532;1003.2024540490328;1009.1371877045168;1021.6168936648756;1025.7589466475372;1032.7211652853687;6046.395360463918;6239.083836138409;6281.466075383552;6424.518161137933;6450.290468048511;625.5808608232495;634.4860662863096;636.0586332228328;642.0593830039015;652.6671397587264;4103.9179216415505;4142.544320124784;4152.314868046738;4178.895527611834;4215.283401821636;10681.252312338062;10997.450127493594;11075.05862001739;11083.50124244411;11108.722281943015;11026.779464410667;11029.503672334764;11133.610323638633;11142.554297827995;11167.776644467227;8378.748637540884;8714.346424018224;8718.215360617574;8730.301575873915;8763.780905477413;6336.3030066693545;6362.713677884413;6374.426301632875;6532.314107018885;6667.899981500184;10215.182785377301;10312.329698569569;10320.916303788294;10422.530986105796;10476.319039262424;10046.748831279276;10252.487375631337;10327.793444131226;10442.216833737652;10443.94778026105;7417.666205020895;7565.43259255547;7565.43259255547;7954.920450795649;8172.264496195371;8315.792105197419;8566.100559441169;8756.124387561193;8848.292136629183;8986.68546600866;8015.47899386538;8023.318017968538;8033.919660803317;8066.435349525477;8119.715809946623;7913.3669306456495;7922.4850384724805;7962.044554653478;7973.20267973198;8005.719799806979;7108.537945033556;7112.893306600508;7143.178534468602;7224.855502890018;7245.347918687133;5412.296401467784;5430.83707488776;5442.972785136049;5511.311086114236;5606.299212598426;5567.638103523258;5594.188842617879;5613.073842816053;5681.659100453986;5747.9137812932 17:46:34 INFO - PROCESS | 20555 | |4;prototype.html;396.9781662008588;403.60446762173075;410.3311602088595;410.8582539024036;413.3427849718946;5355.089634762095;5372.301600791901;5391.32608423947;5406.540444062254;5472.179173124034;5054.989002199556;5194.142966410547;5217.817376391827;5315.176147697103;5343.7328133593355;4337.761423121727;4343.6959412841115;4359.258925982591;4359.934600980988;4360.760158191299;4359.934600980988;4372.366006928994;4380.540043295456;4388.97805510972;4416.5583441655735;4078.8572399966006;4127.855525056623;4261.446012018433;4303.978480107595;4312.05134870328;4283.7429754214845;4285.899909044577;4286.935695964563;4297.763623000734;4311.5472875348105;3899.610038996106;3909.902252443683;3918.6669133123432;3927.3716205407;3932.7247092703524;5154.4587818278915;5154.664946778485;5193.272941788153;5205.29728486657;5258.421573626898;4888.902221955541;4890.24201248807;4893.266010098479;4901.803927842882;4902.3872015998;5543.362513310981;5555.527780138693;5577.749001294951;5596.160575913605;5599.244102046253;4130.545639979599;4175.692538058398;4253.149370125987;4268.502925788417;4276.550962148992;5802.419758024206;5806.564507661942;5809.7095145242565;5812.244408226903;5813.157092221646;4372.1692878353015;4380.474343078851;4387.583179597997;4395.1868904252815;4397.758123303185;4035.6973227008093;4201.264778663722;4233.5554766749665;4251.341042138473;4463.152001119777;4774.164521208774;4780.306855505932;4785.641076919245;4786.688865223749;4800.3759511263315;5602.327720673546;5647.632903861236;5648.350439699405;5684.573656975744;5689.601727879009;1371.5473893615083;1376.4287820554557;1414.964625884361;1437.7268319019354;1452.244832687018;943.7970836270098;1073.2540884085643;1102.2009043443438;1116.2465335898398;1116.9556464705527;5282.392524859613;5347.465253474699;5393.649412788156;5393.979371551944;5413;5515.338159420895;5516.862078448071;5618.97190514053;5633.57748168889;5661.7735290588735;5303.681779093266;5314.4951229633125;5322.707251101227;5349.705766182819;5357.598180136428;2959.4525012872687;2961.7926745127634;2976.360082582212;2979.9851000745293;2982.657803988214;5541.251930989265;5636.971815140898;5713.3718489495295;5806.361300256968;5838.416158384212;5536.584756143306;5570.387257401682;5599.60802743804;5618.763871948165;5640.266765320564;5690.487856092972;5737.77048918047;5740.54075673945;5742.856428589235;5763.106718458645;5301.390340110936;5497.175423686479;5511.393746687781;5532.30846144232;5548.833534993957;5447.700376479332;5451.700156491431;5451.781928722807;5455.263539422127;5513.09034009384;5463.890722185613;5737.885242295214;5792.797252096157;5815.098659707752;5816.680082595498;4330.393744875657;4334.393184954172;4336.826526939013;4341.565843415759;4357.891052723708;5546.861328466659;5572.582056345709;5612.5509959202345;5633.718314084362;5647.85880352978;5188.351456067991;5374.301340825824;5432.511074003359;5510.1459273812625;5524.14375771756;5373.758180881948;5391.865203369947;5392.218128371443;5407.080796264723;5413.10683737179;5368.3158420789605;5406.270153529381;5425.643589102724;5466.835994920159;5474.2609747683555;5587.804426845203;5590.301212348457;5601.187827764869;5607.80372686954;5621.88756224865;5471.23402723627;5480.534154596745;5493.7802487900035;5509.449055094618;5520.779168833202 17:46:34 INFO - PROCESS | 20555 | |5;yui.html;804.5052292839902;818.819859630881;833.1543483364386;897.6140259688333;913.2146354135442;16175.352985880565;16989.575260618476;17698.823011769877;17806.376776812813;18015.63968720628;14863.108213507197;14929.850701492982;15479.613009674764;15939;15975.440859569917;826.1160558202725;834.7913021744564;834.8038211020405;835.8286551256978;835.8620827563444;703.7220297982294;720.3732752505327;798.2376830127215;810.0643756461267;818.6807145213371;865.208334374047;867.7873920889359;867.9392442529025;869.4001139213954;869.9347548933823;862.2326129744532;862.9309655227567;863.2705363967438;863.7020228021322;863.9568021598894;529.068838843638;544.4174733035653;604.7732100462326;613.0191693290744;630.3349965786094;15835.44575939848;15877.682446351007;15878.285477153557;15920;15957.441489547926;15154.242287885672;15213.087214767149;15407.229638518029;15442.691146177014;15636.452724154713;13732.65668358299;13964.162150270915;13975;14076.225807580578;14173.929130354385;4572.268437050089;4587.311903214511;4598.241290187115;4767.094252092092;4789;75592;77082.22917770862;78171.60914195393;78930.60534697348;80143.59928200321;11976.28142311464;12140.878591213976;12230.816537751942;12247.203931744409;12261.938690306491;23706.288811335686;23902.521949560913;24129.396765081012;24235.151769687975;24396.390090247885;8602.784930376665;8626.741197764077;8635.395522313378;8653.826923461496;8777.29781617469;4550.567696068868;4562.429696287964;4571.954280457219;4662.487126416091;4668.416447944007;3869.245497128033;3897.4738410314812;3899.5515515716056;3914.4911161548816;3959.366501359826;623.7442648514087;625.1591609285504;635.2504045226677;643.954923155384;645.0131299112365;757.9166291707908;760.8972788673569;764.1556080531044;766.5477368352699;781.1017330070464;10807.891921080845;10992.395418251914;11085.33487990707;11305.60430384949;11363;727.7998550398577;728.482777228174;744.1219361153891;757.1822431773671;759.1497522774528;5063.772130254148;5232.822614911614;5322.281491998608;5357.973210133924;5437.945620543744;60199.39800602025;69427.3057269431;76792.61603691945;78788.60605696935;79938;69959.65020174866;70088.2991170092;70512;70862;72959;61388.38611613916;61640;61778.9110544471;62184.68907655523;64231.99999999907;10038.698839034842;10051.296409251574;10358.171346292127;10359.792804143724;10398.27212095146;44124;44509;45638.543614564755;45990;46301.30548041715;53294.73352633212;53683.19475207953;55328.72335638296;55951.720241398536;57414.42585574258;12446;12576.622701318975;12608.117431779687;12726.936365318114;12737.93631031839;13906.51327203543;13922.234277114856;14011.369488373251;14303;14412.927935360256;12876.484940602646;12896.161749486253;13109;13439.798403024159;13460.663483412993;12855.485780568673;13115.147515411467;13169.868301316865;13238.404271807984;13283.601491955256;12834.494747634042;12900.253169574235;13220.338983050617;13318.40179178466;13374.13068150567;11280.379579123228;11315.603953861575;11548.711282218012;11631.243969141979;11801.114916381133;10750.892491075301;10890.183236257471;10910.836337454786;10925.235233533576;10958.780824383308 17:46:34 INFO - PROCESS | 20555 | __end_tp_report 17:46:34 INFO - PROCESS | 20555 | __start_cc_report 17:46:34 INFO - PROCESS | 20555 | _x_x_mozilla_cycle_collect,980 17:46:34 INFO - PROCESS | 20555 | __end_cc_report 17:46:34 INFO - PROCESS | 20555 | __startTimestamp1486691194684__endTimestamp 17:46:34 INFO - PROCESS | 20555 | 17:46:34 INFO - PROCESS | 20555 | ------- Summary: start ------- 17:46:34 INFO - PROCESS | 20555 | Number of tests: 6 17:46:34 INFO - PROCESS | 20555 | 17:46:34 INFO - PROCESS | 20555 | [#0] dojo.html Cycles:165 Average:5563.65 Median:5815.10 stddev:623.93 (10.7%) stddev-sans-first:599.93 17:46:34 INFO - PROCESS | 20555 | Values: 3295.3 3320.2 3321.6 3352.5 3375.3 5478.9 5554.4 5577.4 5584.3 5595.0 5571.4 5637.2 5637.8 5675.5 5682.0 5087.7 5114.5 5121.6 5158.9 5187.2 5000.8 5018.3 5197.3 5197.7 5225.7 5027.1 5048.6 5070.2 5124.3 5141.8 5156.4 5156.8 5158.9 5163.5 5188.0 4887.7 5055.3 5072.9 5132.7 5133.6 5435.2 5465.7 5513.7 5534.9 5598.1 5968.0 5986.6 6011.2 6016.6 6021.8 6062.0 6070.7 6073.9 6084.1 6088.7 5397.4 5418.2 5436.1 5444.8 5674.6 5936.5 5950.6 5953.2 5953.5 5994.2 5890.7 5923.3 5946.9 5949.8 5953.0 5810.7 5859.8 5884.1 6032.5 6042.0 5924.6 5957.2 5997.3 6026.2 6031.2 5984.2 5989.5 5991.6 5997.3 5998.0 4468.9 4511.1 4615.4 4644.0 4651.5 3938.5 4032.9 4084.1 4128.5 4140.9 5726.8 5727.4 5728.4 5729.2 5729.4 5835.0 5874.9 5897.5 5986.1 6049.8 5614.8 5662.2 5701.1 5702.3 5720.2 5062.6 5072.3 5074.6 5076.1 5102.3 6105.5 6116.7 6123.1 6250.6 6269.5 5963.6 6030.1 6035.8 6057.1 6075.8 6228.1 6252.6 6260.6 6270.7 6273.0 5685.0 5777.1 5939.4 5943.4 5984.2 5906.2 5929.5 5964.4 6023.9 6060.1 5835.6 5892.2 5905.9 5906.5 5907.6 5602.3 5896.8 5900.2 5904.5 5909.1 5703.2 5762.3 5826.4 5831.5 5916.5 5878.6 5932.0 5934.0 5939.9 5944.9 5813.4 5815.1 5816.7 5821.7 5823.6 17:46:34 INFO - PROCESS | 20555 | 17:46:34 INFO - PROCESS | 20555 | [#1] ext.html Cycles:180 Average:58483.84 Median:37637.31 stddev:107909.10 (286.7%) stddev-sans-first:108124.13 17:46:34 INFO - PROCESS | 20555 | Values: 544.4 591.6 613.1 615.5 636.7 36879.8 47875.8 49120.5 49125.8 49134.3 50834.7 51645.5 51728.7 51740.7 51778.7 8460.4 8646.0 9240.7 9489.4 11195.2 10896.2 11319.9 11322.0 11328.7 11335.4 11252.0 11338.7 11340.3 11350.3 11394.7 11293.3 11351.6 11367.7 11377.7 11380.3 11183.5 11265.2 11274.1 11279.8 11282.2 46559.8 46996.5 47079.3 47144.0 47193.3 4336.8 4366.8 4416.7 4481.4 4538.3 33748.0 34008.0 34356.3 34442.7 35586.5 4326.2 4335.5 4369.2 4379.1 4466.5 361761.0 363009.0 363321.0 364335.2 365444.0 67921.0 67989.0 68153.7 68301.3 68391.0 71788.3 71827.0 71841.3 71869.3 71967.6 19649.6 20138.4 20286.9 20296.6 20307.6 14140.5 14230.4 14464.1 14743.6 14815.8 19592.8 19961.9 19973.9 19982.0 20051.0 6493.7 6521.5 6549.4 6713.5 6738.5 965.5 990.5 993.4 995.5 1003.1 32951.8 33462.8 33608.2 33931.2 34396.8 1060.0 1173.6 1176.6 1195.8 1215.1 17415.0 17714.3 17721.7 18012.0 18060.3 472587.0 473451.0 476309.0 477070.0 477735.0 58980.4 59192.7 59442.0 59442.0 59576.7 1494.4 1495.5 1531.1 1541.6 1561.6 30064.0 30944.8 31446.4 31486.8 31975.8 49273.3 49935.8 49945.3 50016.0 50020.2 378558.0 380309.0 380816.0 380904.0 381262.0 25934.0 26416.2 27378.3 27550.4 28414.6 44872.0 45346.0 45379.8 45637.1 45642.0 37571.6 37709.6 37906.4 38916.6 39411.2 36688.1 36745.0 37703.0 37964.6 37973.8 37832.8 38364.4 38420.6 38679.8 39267.0 31323.7 33411.0 33557.3 33561.5 33584.0 25754.5 25965.7 26019.9 26300.6 26943.6 17:46:34 INFO - PROCESS | 20555 | 17:46:34 INFO - PROCESS | 20555 | [#2] jquery.html Cycles:180 Average:32525.48 Median:40640.19 stddev:99289.43 (244.3%) stddev-sans-first:99538.70 17:46:34 INFO - PROCESS | 20555 | Values: 424.3 426.8 429.7 432.9 450.5 19858.3 20331.3 21437.2 21635.8 21753.8 19446.8 20515.9 20578.3 20682.7 21093.0 5961.6 6204.6 6212.7 6217.9 6219.6 5971.0 6015.5 6070.2 6093.8 6093.9 6063.8 6066.7 6067.7 6076.5 6078.2 5848.9 6039.7 6069.1 6085.7 6088.3 5245.1 5393.1 5418.9 5428.5 5444.8 24928.1 25349.9 25392.4 25436.2 25650.6 3231.5 3275.5 3300.6 3348.2 3349.7 15077.8 15560.8 15670.5 16015.9 16282.9 2008.1 2024.6 2034.5 2036.0 2051.9 83056.2 83723.6 84430.0 85176.1 87785.6 11422.2 11563.2 11603.9 11647.3 11752.3 14997.0 15098.6 15174.7 15192.6 15231.2 8843.6 9065.0 9116.1 9167.0 9199.2 8869.0 8896.2 8905.1 9138.2 9160.0 7117.6 7297.3 7348.3 7372.6 7449.9 2033.2 2054.5 2063.0 2092.2 2099.6 946.4 949.7 951.9 958.8 971.1 19795.8 21482.0 21501.1 21562.6 21721.9 976.8 979.8 989.4 993.3 994.2 11252.5 12027.4 12086.6 12109.0 12189.4 553084.0 610533.0 611827.0 623591.2 629636.0 84716.0 84834.0 86526.0 87244.6 88668.0 1147.2 1179.4 1182.9 1184.4 1196.8 17055.6 17847.6 18168.5 18442.2 18506.0 39984.0 40428.2 40852.2 40887.8 41271.6 51081.7 52201.0 52778.7 53771.2 54242.5 8472.3 8515.0 8889.8 9045.8 9203.5 8345.4 12044.9 13061.8 13136.2 13992.3 10256.3 10539.3 10678.2 10727.4 10980.9 10535.7 11083.3 11163.4 11251.5 11366.1 11348.5 11353.9 11399.2 11406.4 11535.9 8785.2 8794.8 8840.5 9002.8 9011.0 10767.9 10802.4 11076.0 11095.7 11428.0 17:46:34 INFO - PROCESS | 20555 | 17:46:34 INFO - PROCESS | 20555 | [#3] mootools.html Cycles:180 Average:5967.17 Median:4521.46 stddev:3370.38 (74.5%) stddev-sans-first:3362.22 17:46:34 INFO - PROCESS | 20555 | Values: 1381.8 1492.5 1494.0 1520.1 1526.5 7971.3 8816.3 9078.5 9092.7 9329.7 9112.6 9163.5 9171.2 9186.0 9201.2 2046.8 2115.4 2365.1 2402.1 2402.7 2371.8 2425.1 2440.3 2447.3 2458.0 2337.8 2353.5 2370.9 2371.1 2376.1 2344.1 2368.4 2389.9 2393.5 2396.9 2301.1 2360.5 2363.1 2364.7 2364.8 9070.8 9134.9 9138.2 9141.6 9145.0 5363.8 5481.1 5485.7 5486.6 5493.5 3353.3 3492.9 3545.7 3598.7 3623.9 1401.8 1460.7 1479.1 1531.5 1538.8 11925.4 12530.5 12900.8 12913.7 12919.4 4518.3 4655.8 4770.4 4775.8 4800.5 10169.5 10169.9 10182.8 10183.7 10184.8 5533.2 5540.3 5545.0 5546.8 5553.9 4524.6 4738.5 5031.2 5162.8 5228.2 4023.4 4053.4 4097.1 4120.3 4133.7 1152.6 1153.3 1154.3 1157.8 1171.4 1003.2 1009.1 1021.6 1025.8 1032.7 6046.4 6239.1 6281.5 6424.5 6450.3 625.6 634.5 636.1 642.1 652.7 4103.9 4142.5 4152.3 4178.9 4215.3 10681.3 10997.5 11075.1 11083.5 11108.7 11026.8 11029.5 11133.6 11142.6 11167.8 8378.7 8714.3 8718.2 8730.3 8763.8 6336.3 6362.7 6374.4 6532.3 6667.9 10215.2 10312.3 10320.9 10422.5 10476.3 10046.7 10252.5 10327.8 10442.2 10443.9 7417.7 7565.4 7565.4 7954.9 8172.3 8315.8 8566.1 8756.1 8848.3 8986.7 8015.5 8023.3 8033.9 8066.4 8119.7 7913.4 7922.5 7962.0 7973.2 8005.7 7108.5 7112.9 7143.2 7224.9 7245.3 5412.3 5430.8 5443.0 5511.3 5606.3 5567.6 5594.2 5613.1 5681.7 5747.9 17:46:34 INFO - PROCESS | 20555 | 17:46:34 INFO - PROCESS | 20555 | [#4] prototype.html Cycles:180 Average:4712.84 Median:5348.59 stddev:1315.05 (24.6%) stddev-sans-first:1278.22 17:46:34 INFO - PROCESS | 20555 | Values: 397.0 403.6 410.3 410.9 413.3 5355.1 5372.3 5391.3 5406.5 5472.2 5055.0 5194.1 5217.8 5315.2 5343.7 4337.8 4343.7 4359.3 4359.9 4360.8 4359.9 4372.4 4380.5 4389.0 4416.6 4078.9 4127.9 4261.4 4304.0 4312.1 4283.7 4285.9 4286.9 4297.8 4311.5 3899.6 3909.9 3918.7 3927.4 3932.7 5154.5 5154.7 5193.3 5205.3 5258.4 4888.9 4890.2 4893.3 4901.8 4902.4 5543.4 5555.5 5577.7 5596.2 5599.2 4130.5 4175.7 4253.1 4268.5 4276.6 5802.4 5806.6 5809.7 5812.2 5813.2 4372.2 4380.5 4387.6 4395.2 4397.8 4035.7 4201.3 4233.6 4251.3 4463.2 4774.2 4780.3 4785.6 4786.7 4800.4 5602.3 5647.6 5648.4 5684.6 5689.6 1371.5 1376.4 1415.0 1437.7 1452.2 943.8 1073.3 1102.2 1116.2 1117.0 5282.4 5347.5 5393.6 5394.0 5413.0 5515.3 5516.9 5619.0 5633.6 5661.8 5303.7 5314.5 5322.7 5349.7 5357.6 2959.5 2961.8 2976.4 2980.0 2982.7 5541.3 5637.0 5713.4 5806.4 5838.4 5536.6 5570.4 5599.6 5618.8 5640.3 5690.5 5737.8 5740.5 5742.9 5763.1 5301.4 5497.2 5511.4 5532.3 5548.8 5447.7 5451.7 5451.8 5455.3 5513.1 5463.9 5737.9 5792.8 5815.1 5816.7 4330.4 4334.4 4336.8 4341.6 4357.9 5546.9 5572.6 5612.6 5633.7 5647.9 5188.4 5374.3 5432.5 5510.1 5524.1 5373.8 5391.9 5392.2 5407.1 5413.1 5368.3 5406.3 5425.6 5466.8 5474.3 5587.8 5590.3 5601.2 5607.8 5621.9 5471.2 5480.5 5493.8 5509.4 5520.8 17:46:34 INFO - PROCESS | 20555 | 17:46:34 INFO - PROCESS | 20555 | [#5] yui.html Cycles:180 Average:17855.18 Median:4572.11 stddev:22087.45 (483.1%) stddev-sans-first:22112.30 17:46:34 INFO - PROCESS | 20555 | Values: 804.5 818.8 833.2 897.6 913.2 16175.4 16989.6 17698.8 17806.4 18015.6 14863.1 14929.9 15479.6 15939.0 15975.4 826.1 834.8 834.8 835.8 835.9 703.7 720.4 798.2 810.1 818.7 865.2 867.8 867.9 869.4 869.9 862.2 862.9 863.3 863.7 864.0 529.1 544.4 604.8 613.0 630.3 15835.4 15877.7 15878.3 15920.0 15957.4 15154.2 15213.1 15407.2 15442.7 15636.5 13732.7 13964.2 13975.0 14076.2 14173.9 4572.3 4587.3 4598.2 4767.1 4789.0 75592.0 77082.2 78171.6 78930.6 80143.6 11976.3 12140.9 12230.8 12247.2 12261.9 23706.3 23902.5 24129.4 24235.2 24396.4 8602.8 8626.7 8635.4 8653.8 8777.3 4550.6 4562.4 4572.0 4662.5 4668.4 3869.2 3897.5 3899.6 3914.5 3959.4 623.7 625.2 635.3 644.0 645.0 757.9 760.9 764.2 766.5 781.1 10807.9 10992.4 11085.3 11305.6 11363.0 727.8 728.5 744.1 757.2 759.1 5063.8 5232.8 5322.3 5358.0 5437.9 60199.4 69427.3 76792.6 78788.6 79938.0 69959.7 70088.3 70512.0 70862.0 72959.0 61388.4 61640.0 61778.9 62184.7 64232.0 10038.7 10051.3 10358.2 10359.8 10398.3 44124.0 44509.0 45638.5 45990.0 46301.3 53294.7 53683.2 55328.7 55951.7 57414.4 12446.0 12576.6 12608.1 12726.9 12737.9 13906.5 13922.2 14011.4 14303.0 14412.9 12876.5 12896.2 13109.0 13439.8 13460.7 12855.5 13115.1 13169.9 13238.4 13283.6 12834.5 12900.3 13220.3 13318.4 13374.1 11280.4 11315.6 11548.7 11631.2 11801.1 10750.9 10890.2 10910.8 10925.2 10958.8 17:46:34 INFO - PROCESS | 20555 | -------- Summary: end -------- 17:46:34 INFO - PROCESS | 20555 | 17:46:35 INFO - TEST-INFO | 20555: exit 0 17:46:35 INFO - TEST-OK | dromaeo_css | took 1113848ms 17:46:35 INFO - TEST-START | kraken 17:46:35 INFO - operating with platform_type : linux_ 17:46:35 INFO - Initialising browser for kraken test... 17:46:35 INFO - TEST-INFO | started process 20870 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3PUk9J/profile http://localhost:38934/getInfo.html) 17:46:39 INFO - PROCESS | 20870 | __metrics Screen width/height:1600/1200 17:46:39 INFO - PROCESS | 20870 | colorDepth:24 17:46:39 INFO - PROCESS | 20870 | Browser inner width/height: 1024/697 17:46:39 INFO - PROCESS | 20870 | __metrics 17:46:40 INFO - PROCESS | 20870 | 1486691200149 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 17:46:40 INFO - PROCESS | 20870 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:46:40 INFO - PROCESS | 20870 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:46:41 INFO - TEST-INFO | 20870: exit 0 17:46:41 INFO - Browser initialized. 17:46:41 INFO - Running cycle 1/1 for kraken test... 17:46:41 INFO - TEST-INFO | started process 20961 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3PUk9J/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:46:53 INFO - PROCESS | 20961 | RSS: Main: 138207232 17:46:53 INFO - PROCESS | 20961 | 17:46:55 INFO - PROCESS | 20961 | Cycle 1(1): loaded http://localhost:38934/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38934/tests/kraken/driver.html?testName=audio-beat-detection) 17:46:56 INFO - PROCESS | 20961 | RSS: Main: 184291328 17:46:56 INFO - PROCESS | 20961 | 17:46:59 INFO - PROCESS | 20961 | Cycle 1(1): loaded http://localhost:38934/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38934/tests/kraken/driver.html?testName=audio-dft) 17:46:59 INFO - PROCESS | 20961 | RSS: Main: 172154880 17:46:59 INFO - PROCESS | 20961 | 17:47:02 INFO - PROCESS | 20961 | Cycle 1(1): loaded http://localhost:38934/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38934/tests/kraken/driver.html?testName=audio-fft) 17:47:03 INFO - PROCESS | 20961 | RSS: Main: 263548928 17:47:03 INFO - PROCESS | 20961 | 17:47:05 INFO - PROCESS | 20961 | Cycle 1(1): loaded http://localhost:38934/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38934/tests/kraken/driver.html?testName=audio-oscillator) 17:47:05 INFO - PROCESS | 20961 | RSS: Main: 169320448 17:47:05 INFO - PROCESS | 20961 | 17:47:07 INFO - PROCESS | 20961 | Cycle 1(1): loaded http://localhost:38934/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38934/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:47:08 INFO - PROCESS | 20961 | RSS: Main: 172142592 17:47:08 INFO - PROCESS | 20961 | 17:47:11 INFO - PROCESS | 20961 | Cycle 1(1): loaded http://localhost:38934/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38934/tests/kraken/driver.html?testName=imaging-darkroom) 17:47:11 INFO - PROCESS | 20961 | RSS: Main: 211324928 17:47:11 INFO - PROCESS | 20961 | 17:47:15 INFO - PROCESS | 20961 | Cycle 1(1): loaded http://localhost:38934/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38934/tests/kraken/driver.html?testName=imaging-desaturate) 17:47:15 INFO - PROCESS | 20961 | RSS: Main: 209932288 17:47:15 INFO - PROCESS | 20961 | 17:47:18 INFO - PROCESS | 20961 | Cycle 1(1): loaded http://localhost:38934/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38934/tests/kraken/driver.html?testName=json-parse-financial) 17:47:19 INFO - PROCESS | 20961 | RSS: Main: 208310272 17:47:19 INFO - PROCESS | 20961 | 17:47:20 INFO - PROCESS | 20961 | Cycle 1(1): loaded http://localhost:38934/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38934/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:47:21 INFO - PROCESS | 20961 | RSS: Main: 170872832 17:47:21 INFO - PROCESS | 20961 | 17:47:23 INFO - PROCESS | 20961 | Cycle 1(1): loaded http://localhost:38934/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38934/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:47:23 INFO - PROCESS | 20961 | RSS: Main: 178421760 17:47:23 INFO - PROCESS | 20961 | 17:47:25 INFO - PROCESS | 20961 | Cycle 1(1): loaded http://localhost:38934/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38934/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:47:26 INFO - PROCESS | 20961 | RSS: Main: 186929152 17:47:26 INFO - PROCESS | 20961 | 17:47:28 INFO - PROCESS | 20961 | Cycle 1(1): loaded http://localhost:38934/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38934/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:47:29 INFO - PROCESS | 20961 | RSS: Main: 196780032 17:47:29 INFO - PROCESS | 20961 | 17:47:32 INFO - PROCESS | 20961 | Cycle 1(1): loaded http://localhost:38934/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38934/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:47:32 INFO - PROCESS | 20961 | RSS: Main: 192368640 17:47:32 INFO - PROCESS | 20961 | 17:47:34 INFO - PROCESS | 20961 | Cycle 1(1): loaded http://localhost:38934/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38934/tests/kraken/driver.html?testName=ai-astar) 17:47:34 INFO - PROCESS | 20961 | RSS: Main: 244080640 17:47:34 INFO - PROCESS | 20961 | 17:47:34 INFO - PROCESS | 20961 | __start_tp_report 17:47:34 INFO - PROCESS | 20961 | _x_x_mozilla_page_load 17:47:34 INFO - PROCESS | 20961 | _x_x_mozilla_page_load_details 17:47:34 INFO - PROCESS | 20961 | |i|pagename|runs| 17:47:34 INFO - PROCESS | 20961 | |0;ai-astar;114;113;113;113;114;116;113;113;113;112 17:47:34 INFO - PROCESS | 20961 | |1;audio-beat-detection;147;118;121;158;116;118;117;117;142;119 17:47:34 INFO - PROCESS | 20961 | |2;audio-dft;160;159;159;159;160;159;161;163;163;160 17:47:34 INFO - PROCESS | 20961 | |3;audio-fft;75;75;76;121;76;77;75;77;91;75 17:47:34 INFO - PROCESS | 20961 | |4;audio-oscillator;82;83;111;83;83;83;100;83;83;112 17:47:34 INFO - PROCESS | 20961 | |5;imaging-gaussian-blur;116;117;117;116;117;117;117;117;117;117 17:47:34 INFO - PROCESS | 20961 | |6;imaging-darkroom;145;146;145;147;146;146;146;146;146;145 17:47:34 INFO - PROCESS | 20961 | |7;imaging-desaturate;96;96;95;96;96;96;95;96;95;96 17:47:34 INFO - PROCESS | 20961 | |8;json-parse-financial;49;49;50;48;50;51;50;50;50;49 17:47:34 INFO - PROCESS | 20961 | |9;json-stringify-tinderbox;64;64;65;63;64;63;64;64;64;63 17:47:34 INFO - PROCESS | 20961 | |10;stanford-crypto-aes;79;81;80;78;77;85;77;76;75;79 17:47:34 INFO - PROCESS | 20961 | |11;stanford-crypto-ccm;123;130;126;124;124;121;120;124;159;125 17:47:34 INFO - PROCESS | 20961 | |12;stanford-crypto-pbkdf2;165;158;159;159;158;163;157;158;156;165 17:47:34 INFO - PROCESS | 20961 | |13;stanford-crypto-sha256-iterative;61;62;61;60;60;62;61;61;59;61 17:47:34 INFO - PROCESS | 20961 | __end_tp_report 17:47:34 INFO - PROCESS | 20961 | __start_cc_report 17:47:34 INFO - PROCESS | 20961 | _x_x_mozilla_cycle_collect,1520 17:47:34 INFO - PROCESS | 20961 | __end_cc_report 17:47:34 INFO - PROCESS | 20961 | __startTimestamp1486691254586__endTimestamp 17:47:34 INFO - PROCESS | 20961 | 17:47:34 INFO - PROCESS | 20961 | ------- Summary: start ------- 17:47:34 INFO - PROCESS | 20961 | Number of tests: 14 17:47:34 INFO - PROCESS | 20961 | 17:47:34 INFO - PROCESS | 20961 | [#0] ai-astar Cycles:10 Average:113.40 Median:113.00 stddev:1.07 (1.0%) stddev-sans-first:1.12 17:47:34 INFO - PROCESS | 20961 | Values: 114.0 113.0 113.0 113.0 114.0 116.0 113.0 113.0 113.0 112.0 17:47:34 INFO - PROCESS | 20961 | 17:47:34 INFO - PROCESS | 20961 | [#1] audio-beat-detection Cycles:10 Average:127.30 Median:120.00 stddev:15.52 (12.9%) stddev-sans-first:14.73 17:47:34 INFO - PROCESS | 20961 | Values: 147.0 118.0 121.0 158.0 116.0 118.0 117.0 117.0 142.0 119.0 17:47:34 INFO - PROCESS | 20961 | 17:47:34 INFO - PROCESS | 20961 | [#2] audio-dft Cycles:10 Average:160.30 Median:160.00 stddev:1.57 (1.0%) stddev-sans-first:1.66 17:47:34 INFO - PROCESS | 20961 | Values: 160.0 159.0 159.0 159.0 160.0 159.0 161.0 163.0 163.0 160.0 17:47:34 INFO - PROCESS | 20961 | 17:47:34 INFO - PROCESS | 20961 | [#3] audio-fft Cycles:10 Average:81.80 Median:76.00 stddev:14.60 (19.2%) stddev-sans-first:15.28 17:47:34 INFO - PROCESS | 20961 | Values: 75.0 75.0 76.0 121.0 76.0 77.0 75.0 77.0 91.0 75.0 17:47:34 INFO - PROCESS | 20961 | 17:47:34 INFO - PROCESS | 20961 | [#4] audio-oscillator Cycles:10 Average:90.30 Median:83.00 stddev:12.39 (14.9%) stddev-sans-first:12.77 17:47:34 INFO - PROCESS | 20961 | Values: 82.0 83.0 111.0 83.0 83.0 83.0 100.0 83.0 83.0 112.0 17:47:34 INFO - PROCESS | 20961 | 17:47:34 INFO - PROCESS | 20961 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 17:47:34 INFO - PROCESS | 20961 | Values: 116.0 117.0 117.0 116.0 117.0 117.0 117.0 117.0 117.0 117.0 17:47:34 INFO - PROCESS | 20961 | 17:47:34 INFO - PROCESS | 20961 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.63 (0.4%) stddev-sans-first:0.60 17:47:34 INFO - PROCESS | 20961 | Values: 145.0 146.0 145.0 147.0 146.0 146.0 146.0 146.0 146.0 145.0 17:47:34 INFO - PROCESS | 20961 | 17:47:34 INFO - PROCESS | 20961 | [#7] imaging-desaturate Cycles:10 Average:95.70 Median:96.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 17:47:34 INFO - PROCESS | 20961 | Values: 96.0 96.0 95.0 96.0 96.0 96.0 95.0 96.0 95.0 96.0 17:47:34 INFO - PROCESS | 20961 | 17:47:34 INFO - PROCESS | 20961 | [#8] json-parse-financial Cycles:10 Average:49.60 Median:50.00 stddev:0.84 (1.7%) stddev-sans-first:0.87 17:47:34 INFO - PROCESS | 20961 | Values: 49.0 49.0 50.0 48.0 50.0 51.0 50.0 50.0 50.0 49.0 17:47:34 INFO - PROCESS | 20961 | 17:47:34 INFO - PROCESS | 20961 | [#9] json-stringify-tinderbox Cycles:10 Average:63.80 Median:64.00 stddev:0.63 (1.0%) stddev-sans-first:0.67 17:47:34 INFO - PROCESS | 20961 | Values: 64.0 64.0 65.0 63.0 64.0 63.0 64.0 64.0 64.0 63.0 17:47:34 INFO - PROCESS | 20961 | 17:47:34 INFO - PROCESS | 20961 | [#10] stanford-crypto-aes Cycles:10 Average:78.70 Median:79.00 stddev:2.87 (3.6%) stddev-sans-first:3.04 17:47:34 INFO - PROCESS | 20961 | Values: 79.0 81.0 80.0 78.0 77.0 85.0 77.0 76.0 75.0 79.0 17:47:34 INFO - PROCESS | 20961 | 17:47:34 INFO - PROCESS | 20961 | [#11] stanford-crypto-ccm Cycles:10 Average:127.60 Median:124.50 stddev:11.36 (9.1%) stddev-sans-first:11.93 17:47:34 INFO - PROCESS | 20961 | Values: 123.0 130.0 126.0 124.0 124.0 121.0 120.0 124.0 159.0 125.0 17:47:34 INFO - PROCESS | 20961 | 17:47:34 INFO - PROCESS | 20961 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:159.80 Median:159.00 stddev:3.29 (2.1%) stddev-sans-first:2.91 17:47:34 INFO - PROCESS | 20961 | Values: 165.0 158.0 159.0 159.0 158.0 163.0 157.0 158.0 156.0 165.0 17:47:34 INFO - PROCESS | 20961 | 17:47:34 INFO - PROCESS | 20961 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.80 Median:61.00 stddev:0.92 (1.5%) stddev-sans-first:0.97 17:47:34 INFO - PROCESS | 20961 | Values: 61.0 62.0 61.0 60.0 60.0 62.0 61.0 61.0 59.0 61.0 17:47:34 INFO - PROCESS | 20961 | -------- Summary: end -------- 17:47:34 INFO - PROCESS | 20961 | 17:47:35 INFO - TEST-INFO | 20961: exit 0 17:47:35 INFO - TEST-OK | kraken | took 60225ms 17:47:35 INFO - SUITE-END | took 1174s 17:47:35 INFO - Completed test suite (00:19:34) 17:47:35 INFO - javascript benchmark 17:47:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3295.258566822465, 3320.2031512437006, 3321.568196134502, 3352.4971254311868, 3375.257443362461, 5478.890422191554, 5554.361248456424, 5577.358603760569, 5584.301962254718, 5595.048841696911, 5571.359293681227, 5637.210790489335, 5637.830865074055, 5675.51758100562, 5682.0, 5087.694738315695, 5114.488551144875, 5121.564667003309, 5158.8710282243055, 5187.19598462239, 5000.799968001294, 5018.34761481007, 5197.298364720752, 5197.740112994354, 5225.712585807779, 5027.069992051483, 5048.570871475929, 5070.163423035195, 5124.256982737501, 5141.794328226866, 5156.381234251895, 5156.767945442427, 5158.948410515922, 5163.535281824654, 5188.040212560651, 4887.70673759572, 5055.292259083732, 5072.923906141411, 5132.74336283188, 5133.563647090002, 5435.211894275348, 5465.672059676393, 5513.724313784295, 5534.889302213974, 5598.104303311491, 5967.970160149215, 5986.610870293461, 6011.248593925759, 6016.608920420159, 6021.849453763647, 6061.969690151521, 6070.696465176724, 6073.908891366633, 6084.117802918597, 6088.66512341821, 5397.4062853086125, 5418.241446197536, 5436.103042997861, 5444.754986025599, 5674.631148975344, 5936.495397891142, 5950.553708471795, 5953.166556682068, 5953.523718102541, 5994.1608174855555, 5890.705464726832, 5923.289205295392, 5946.851328716817, 5949.762009519657, 5952.987992041363, 5810.709464526757, 5859.794907178229, 5884.058550631964, 6032.547558933097, 6041.969790151021, 5924.6149000314845, 5957.195778569838, 5997.250343707036, 6026.216591843032, 6031.215941927521, 5984.222051133412, 5989.520838332923, 5991.58058935879, 5997.250343707036, 5997.970010150008, 4468.865934021985, 4511.142882852248, 4615.446146462379, 4644.024754801462, 4651.465081515601, 3938.4879965604287, 4032.858849940238, 4084.0606660467665, 4128.545859955402, 4140.91718165635, 5726.770929162787, 5727.3699893011735, 5728.369879313273, 5729.226554415184, 5729.369769325371, 5835.037218858841, 5874.882502349929, 5897.49871260939, 5986.102084687332, 6049.848753781191, 5614.831555053355, 5662.207290979266, 5701.087825947829, 5702.258706368146, 5720.170575266563, 5062.645614806928, 5072.315237442972, 5074.5686616637995, 5076.111680455905, 5102.311187989574, 6105.45050945417, 6116.731440059295, 6123.099607468126, 6250.593711408744, 6269.498440124779, 5963.642181469126, 6030.1256317832795, 6035.849103772441, 6057.091436284592, 6075.787347442818, 6228.096925945803, 6252.562320637512, 6260.624362538262, 6270.717817698123, 6272.968635156795, 5685.0335442973965, 5777.133429985536, 5939.376365481562, 5943.435373639497, 5984.162217289496, 5906.232189815297, 5929.495992840657, 5964.433378829004, 6023.909641355471, 6060.1212824139375, 5835.56233282497, 5892.204552385373, 5905.881882362242, 5906.527477801851, 5907.616004959664, 5602.327720673382, 5896.823095307148, 5900.232969713909, 5904.468597826216, 5909.054551271776, 5703.230063941315, 5762.30852297727, 5826.359100498857, 5831.504322132666, 5916.526677865674, 5878.559108066826, 5932.0, 5933.970330148494, 5939.851503712442, 5944.8811023780145, 5813.418658134153, 5815.098659707752, 5816.709164541671, 5821.7089145543405, 5823.5923485355615], "value": 5522.610068844035, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [544.3541515524804, 591.5592883301939, 613.0926229180812, 615.5014438304974, 636.6880228687938, 36879.81560092199, 47875.76062119693, 49120.50879491204, 49125.7543712281, 49134.26298605524, 50834.74582627082, 51645.483545164534, 51728.74135629317, 51740.74129629356, 51778.741106294416, 8460.36547258955, 8646.0, 9240.722778316662, 9489.383190092622, 11195.160362972809, 10896.18278629102, 11319.943400282988, 11322.0, 11328.716782080473, 11335.37655428951, 11252.0, 11338.716532086723, 11340.319580825177, 11350.262232954832, 11394.658160255205, 11293.322400655947, 11351.602693905674, 11367.658970230907, 11377.715557111138, 11380.317180969168, 11183.496742646601, 11265.155113366449, 11274.09807215429, 11279.774404511945, 11282.210245282833, 46559.76720116378, 46996.53003469956, 47079.29381059287, 47144.0, 47193.29210061852, 4336.783160841946, 4366.8034938427845, 4416.668749843742, 4481.395011673448, 4538.251188553884, 33748.0, 34008.0, 34356.31287374238, 34442.65557344445, 35586.466203006974, 4326.242907491176, 4335.479742430897, 4369.213541562517, 4379.124175164948, 4466.508684044752, 361761.0, 363009.0, 363321.0, 364335.17832410935, 365444.0, 67921.0, 67989.0, 68153.65923170451, 68301.31698683048, 68391.0, 71788.2821171792, 71827.0, 71841.28158718346, 71869.28130718731, 71967.6401617999, 19649.607007859762, 20138.39584812458, 20286.898565507374, 20296.59406811885, 20307.593848122953, 14140.50508232207, 14230.430782768573, 14464.059836110619, 14743.63140921464, 14815.777763333559, 19592.804071959097, 19961.900190498956, 19973.900130499256, 19982.0, 20051.0, 6493.675316234169, 6521.478281737544, 6549.410553050153, 6713.4964877634375, 6738.528303018839, 965.4786415335781, 990.4997976022207, 993.3592832623062, 995.5470261031213, 1003.0721582536125, 32951.835240823646, 33462.832685836416, 33608.1597960053, 33931.15172120717, 34396.82801585976, 1059.957601695939, 1173.6244401791516, 1176.5587904535798, 1195.7847587434346, 1215.0522592377959, 17415.0, 17714.291428342978, 17721.7341739874, 18012.0, 18060.27758889656, 472587.0, 473451.0, 476309.0, 477070.0, 477735.0, 58980.41019589749, 59192.704036480405, 59442.0, 59442.0, 59576.70211648914, 1494.3873012065, 1495.5438591229563, 1531.1349087765377, 1541.576066581699, 1561.6408226108058, 30064.0, 30944.845275773478, 31446.371072578877, 31486.770222801806, 31975.840120799247, 49273.26090108723, 49935.75032124816, 49945.25082123699, 50016.0, 50020.24969625531, 378558.0, 380309.0, 380816.0, 380904.0, 381262.0, 25934.0, 26416.207513774618, 27378.315542111606, 27550.44899102047, 28414.57378139371, 44872.0, 45346.0, 45379.773101134284, 45637.08725825398, 45642.0, 37571.624283757905, 37709.622903771706, 37906.43140352952, 38916.610833891296, 39411.21177576375, 36688.05713001552, 36745.0, 37703.0, 37964.62035379611, 37973.81013094917, 37832.810835946744, 38364.424533631456, 38420.6157938417, 38679.80660096682, 39267.0, 31323.686763132988, 33411.0, 33557.328853423285, 33561.49657755184, 33584.0, 25754.484910302064, 25965.74034259633, 26019.869900650374, 26300.605490917267, 26943.595846062715], "value": 21119.857715742943, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [424.255431717336, 426.84846879357815, 429.6906227516189, 432.87879393769714, 450.51794579799605, 19858.304959326408, 20331.28840490583, 21437.249696260595, 21635.783642163573, 21753.803540805267, 19446.805531944676, 20515.897420512876, 20578.279760208396, 20682.68975965362, 21093.0, 5961.61249518781, 6204.596701214429, 6212.65830379329, 6217.875642487147, 6219.564630475868, 5971.0, 6015.518758499332, 6070.210872586557, 6093.756249750027, 6093.908591371111, 6063.848403789896, 6066.695660433006, 6067.696615169246, 6076.453119219269, 6078.240219972504, 5848.941510584904, 6039.667818269993, 6069.119977603267, 6085.726142323606, 6088.299845517734, 5245.134552798783, 5393.1101368274185, 5418.945810541923, 5428.511433970962, 5444.8366549003595, 24928.12751553705, 25349.873250633813, 25392.36519087019, 25436.23691289246, 25650.615240771403, 3231.515272709089, 3275.5250488679008, 3300.620428650712, 3348.2299071213515, 3349.681780230874, 15077.849221507864, 15560.766588501181, 15670.451534196362, 16015.919920400323, 16282.918585406996, 2008.066249194117, 2024.6153230886232, 2034.511717187879, 2035.986739625348, 2051.938441846747, 83056.16943830544, 83723.5813820927, 84430.0, 85176.14823851745, 87785.56107219488, 11422.200445968869, 11563.190576659554, 11603.941980290045, 11647.30116193031, 11752.294862308288, 14997.0, 15098.622534436727, 15174.696506069817, 15192.620184495256, 15231.238438078051, 8843.557822108869, 9065.0, 9116.1339672731, 9166.954165229132, 9199.172074513326, 8869.0, 8896.199342059246, 8905.064968178378, 9138.17756401927, 9159.95420022909, 7117.572945623279, 7297.3432391084, 7348.265173482716, 7372.594507302043, 7449.851002980018, 2033.2070492507933, 2054.5274586845107, 2062.989685051565, 2092.204962114387, 2099.5695882344085, 946.3564775952281, 949.7293271417612, 951.8678160919628, 958.8274110660009, 971.1308379000754, 19795.802041979397, 21482.0, 21501.064904158702, 21562.568748625254, 21721.87284415956, 976.8046390721884, 979.8481235408523, 989.391524212604, 993.304686719285, 994.2096033653263, 11252.455893151297, 12027.398630068461, 12086.637400877988, 12109.0, 12189.390530473442, 553084.0, 610533.0, 611827.0, 623591.2110980165, 629636.0, 84716.0, 84834.0, 86526.0, 87244.56377718071, 88668.0, 1147.1511081799574, 1179.3572502985894, 1182.9467673954694, 1184.4196343791652, 1196.7726132034886, 17055.573610659834, 17847.643047138725, 18168.45494635163, 18442.170102345157, 18505.96434392189, 39984.0, 40428.1914361717, 40852.18295634011, 40887.795561022, 41271.58728412678, 51081.74459127681, 52201.0, 52778.73610631922, 53771.19343209933, 54242.45757542374, 8472.279856212044, 8515.0, 8889.822203556021, 9045.764603745503, 9203.493807840365, 8345.374096942633, 12044.879551204376, 13061.804072939103, 13136.211827290394, 13992.300384980506, 10256.28206025601, 10539.262251642618, 10678.199135064746, 10727.356358618508, 10980.890191097988, 10535.736606584896, 11083.279586826831, 11163.44182790841, 11251.49368278413, 11366.090712743126, 11348.489317980546, 11353.886461135613, 11399.20205585601, 11406.372649504357, 11535.884641153481, 8785.165409286108, 8794.824103518022, 8840.513771742615, 9002.81994360096, 9010.966119600544, 10767.946160269148, 10802.351858888493, 11076.0, 11095.667129986114, 11428.0], "value": 9831.472996499937, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1381.8479967203612, 1492.51493264689, 1493.9626509337272, 1520.0575643101276, 1526.473366688492, 7971.282584567388, 8816.338774591899, 9078.500682462462, 9092.727218183456, 9329.720108396741, 9112.635494580209, 9163.49600771959, 9171.174594286513, 9185.954070229656, 9201.21789647879, 2046.7748547659746, 2115.375964090597, 2365.1367250953363, 2402.0752010476003, 2402.723686776017, 2371.7509661485556, 2425.0542288507495, 2440.255722004788, 2447.278052974377, 2457.950840983179, 2337.7662233776655, 2353.505763789606, 2370.8696021718797, 2371.087131454385, 2376.0733314007553, 2344.0740907341506, 2368.3842201027683, 2389.9402514937265, 2393.5093305872256, 2396.8681722505344, 2301.148575027234, 2360.468894498741, 2363.137454828975, 2364.6807680963193, 2364.7635236476385, 9070.773230669287, 9134.862977055349, 9138.223251023672, 9141.634334626608, 9145.039770824034, 5363.839084827461, 5481.068218402881, 5485.6982865942355, 5486.588505862076, 5493.450654934514, 3353.279045005336, 3492.879707415265, 3545.716342692604, 3598.712103031751, 3623.9456408153897, 1401.8317801863739, 1460.7005563859384, 1479.1494890437934, 1531.5252271795669, 1538.8461153884634, 11925.403729813475, 12530.498780048878, 12900.80648790269, 12913.741725165444, 12919.41862616175, 4518.299663552155, 4655.767211639472, 4770.427548694179, 4775.832845850378, 4800.4719480857075, 10169.54237059334, 10169.9491502542, 10182.847257291147, 10183.7454063649, 10184.796304073878, 5533.22534845122, 5540.252065971123, 5545.0, 5546.805861794818, 5553.916691249635, 4524.615407690383, 4738.502457242009, 5031.245313203049, 5162.819301324435, 5228.163493840967, 4023.376376661622, 4053.3514637658423, 4097.057676734308, 4120.299549076664, 4133.669306455537, 1152.6138743521014, 1153.26191237608, 1154.2901115813713, 1157.8494795676509, 1171.4377098992532, 1003.2024540490328, 1009.1371877045168, 1021.6168936648756, 1025.7589466475372, 1032.7211652853687, 6046.395360463918, 6239.083836138409, 6281.466075383552, 6424.518161137933, 6450.290468048511, 625.5808608232495, 634.4860662863096, 636.0586332228328, 642.0593830039015, 652.6671397587264, 4103.9179216415505, 4142.544320124784, 4152.314868046738, 4178.895527611834, 4215.283401821636, 10681.252312338062, 10997.450127493594, 11075.05862001739, 11083.50124244411, 11108.722281943015, 11026.779464410667, 11029.503672334764, 11133.610323638633, 11142.554297827995, 11167.776644467227, 8378.748637540884, 8714.346424018224, 8718.215360617574, 8730.301575873915, 8763.780905477413, 6336.3030066693545, 6362.713677884413, 6374.426301632875, 6532.314107018885, 6667.899981500184, 10215.182785377301, 10312.329698569569, 10320.916303788294, 10422.530986105796, 10476.319039262424, 10046.748831279276, 10252.487375631337, 10327.793444131226, 10442.216833737652, 10443.94778026105, 7417.666205020895, 7565.43259255547, 7565.43259255547, 7954.920450795649, 8172.264496195371, 8315.792105197419, 8566.100559441169, 8756.124387561193, 8848.292136629183, 8986.68546600866, 8015.47899386538, 8023.318017968538, 8033.919660803317, 8066.435349525477, 8119.715809946623, 7913.3669306456495, 7922.4850384724805, 7962.044554653478, 7973.20267973198, 8005.719799806979, 7108.537945033556, 7112.893306600508, 7143.178534468602, 7224.855502890018, 7245.347918687133, 5412.296401467784, 5430.83707488776, 5442.972785136049, 5511.311086114236, 5606.299212598426, 5567.638103523258, 5594.188842617879, 5613.073842816053, 5681.659100453986, 5747.9137812932], "value": 4739.029773399426, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [396.9781662008588, 403.60446762173075, 410.3311602088595, 410.8582539024036, 413.3427849718946, 5355.089634762095, 5372.301600791901, 5391.32608423947, 5406.540444062254, 5472.179173124034, 5054.989002199556, 5194.142966410547, 5217.817376391827, 5315.176147697103, 5343.7328133593355, 4337.761423121727, 4343.6959412841115, 4359.258925982591, 4359.934600980988, 4360.760158191299, 4359.934600980988, 4372.366006928994, 4380.540043295456, 4388.97805510972, 4416.5583441655735, 4078.8572399966006, 4127.855525056623, 4261.446012018433, 4303.978480107595, 4312.05134870328, 4283.7429754214845, 4285.899909044577, 4286.935695964563, 4297.763623000734, 4311.5472875348105, 3899.610038996106, 3909.902252443683, 3918.6669133123432, 3927.3716205407, 3932.7247092703524, 5154.4587818278915, 5154.664946778485, 5193.272941788153, 5205.29728486657, 5258.421573626898, 4888.902221955541, 4890.24201248807, 4893.266010098479, 4901.803927842882, 4902.3872015998, 5543.362513310981, 5555.527780138693, 5577.749001294951, 5596.160575913605, 5599.244102046253, 4130.545639979599, 4175.692538058398, 4253.149370125987, 4268.502925788417, 4276.550962148992, 5802.419758024206, 5806.564507661942, 5809.7095145242565, 5812.244408226903, 5813.157092221646, 4372.1692878353015, 4380.474343078851, 4387.583179597997, 4395.1868904252815, 4397.758123303185, 4035.6973227008093, 4201.264778663722, 4233.5554766749665, 4251.341042138473, 4463.152001119777, 4774.164521208774, 4780.306855505932, 4785.641076919245, 4786.688865223749, 4800.3759511263315, 5602.327720673546, 5647.632903861236, 5648.350439699405, 5684.573656975744, 5689.601727879009, 1371.5473893615083, 1376.4287820554557, 1414.964625884361, 1437.7268319019354, 1452.244832687018, 943.7970836270098, 1073.2540884085643, 1102.2009043443438, 1116.2465335898398, 1116.9556464705527, 5282.392524859613, 5347.465253474699, 5393.649412788156, 5393.979371551944, 5413.0, 5515.338159420895, 5516.862078448071, 5618.97190514053, 5633.57748168889, 5661.7735290588735, 5303.681779093266, 5314.4951229633125, 5322.707251101227, 5349.705766182819, 5357.598180136428, 2959.4525012872687, 2961.7926745127634, 2976.360082582212, 2979.9851000745293, 2982.657803988214, 5541.251930989265, 5636.971815140898, 5713.3718489495295, 5806.361300256968, 5838.416158384212, 5536.584756143306, 5570.387257401682, 5599.60802743804, 5618.763871948165, 5640.266765320564, 5690.487856092972, 5737.77048918047, 5740.54075673945, 5742.856428589235, 5763.106718458645, 5301.390340110936, 5497.175423686479, 5511.393746687781, 5532.30846144232, 5548.833534993957, 5447.700376479332, 5451.700156491431, 5451.781928722807, 5455.263539422127, 5513.09034009384, 5463.890722185613, 5737.885242295214, 5792.797252096157, 5815.098659707752, 5816.680082595498, 4330.393744875657, 4334.393184954172, 4336.826526939013, 4341.565843415759, 4357.891052723708, 5546.861328466659, 5572.582056345709, 5612.5509959202345, 5633.718314084362, 5647.85880352978, 5188.351456067991, 5374.301340825824, 5432.511074003359, 5510.1459273812625, 5524.14375771756, 5373.758180881948, 5391.865203369947, 5392.218128371443, 5407.080796264723, 5413.10683737179, 5368.3158420789605, 5406.270153529381, 5425.643589102724, 5466.835994920159, 5474.2609747683555, 5587.804426845203, 5590.301212348457, 5601.187827764869, 5607.80372686954, 5621.88756224865, 5471.23402723627, 5480.534154596745, 5493.7802487900035, 5509.449055094618, 5520.779168833202], "value": 4315.460760728194, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [804.5052292839902, 818.819859630881, 833.1543483364386, 897.6140259688333, 913.2146354135442, 16175.352985880565, 16989.575260618476, 17698.823011769877, 17806.376776812813, 18015.63968720628, 14863.108213507197, 14929.850701492982, 15479.613009674764, 15939.0, 15975.440859569917, 826.1160558202725, 834.7913021744564, 834.8038211020405, 835.8286551256978, 835.8620827563444, 703.7220297982294, 720.3732752505327, 798.2376830127215, 810.0643756461267, 818.6807145213371, 865.208334374047, 867.7873920889359, 867.9392442529025, 869.4001139213954, 869.9347548933823, 862.2326129744532, 862.9309655227567, 863.2705363967438, 863.7020228021322, 863.9568021598894, 529.068838843638, 544.4174733035653, 604.7732100462326, 613.0191693290744, 630.3349965786094, 15835.44575939848, 15877.682446351007, 15878.285477153557, 15920.0, 15957.441489547926, 15154.242287885672, 15213.087214767149, 15407.229638518029, 15442.691146177014, 15636.452724154713, 13732.65668358299, 13964.162150270915, 13975.0, 14076.225807580578, 14173.929130354385, 4572.268437050089, 4587.311903214511, 4598.241290187115, 4767.094252092092, 4789.0, 75592.0, 77082.22917770862, 78171.60914195393, 78930.60534697348, 80143.59928200321, 11976.28142311464, 12140.878591213976, 12230.816537751942, 12247.203931744409, 12261.938690306491, 23706.288811335686, 23902.521949560913, 24129.396765081012, 24235.151769687975, 24396.390090247885, 8602.784930376665, 8626.741197764077, 8635.395522313378, 8653.826923461496, 8777.29781617469, 4550.567696068868, 4562.429696287964, 4571.954280457219, 4662.487126416091, 4668.416447944007, 3869.245497128033, 3897.4738410314812, 3899.5515515716056, 3914.4911161548816, 3959.366501359826, 623.7442648514087, 625.1591609285504, 635.2504045226677, 643.954923155384, 645.0131299112365, 757.9166291707908, 760.8972788673569, 764.1556080531044, 766.5477368352699, 781.1017330070464, 10807.891921080845, 10992.395418251914, 11085.33487990707, 11305.60430384949, 11363.0, 727.7998550398577, 728.482777228174, 744.1219361153891, 757.1822431773671, 759.1497522774528, 5063.772130254148, 5232.822614911614, 5322.281491998608, 5357.973210133924, 5437.945620543744, 60199.39800602025, 69427.3057269431, 76792.61603691945, 78788.60605696935, 79938.0, 69959.65020174866, 70088.2991170092, 70512.0, 70862.0, 72959.0, 61388.38611613916, 61640.0, 61778.9110544471, 62184.68907655523, 64231.99999999907, 10038.698839034842, 10051.296409251574, 10358.171346292127, 10359.792804143724, 10398.27212095146, 44124.0, 44509.0, 45638.543614564755, 45990.0, 46301.30548041715, 53294.73352633212, 53683.19475207953, 55328.72335638296, 55951.720241398536, 57414.42585574258, 12446.0, 12576.622701318975, 12608.117431779687, 12726.936365318114, 12737.93631031839, 13906.51327203543, 13922.234277114856, 14011.369488373251, 14303.0, 14412.927935360256, 12876.484940602646, 12896.161749486253, 13109.0, 13439.798403024159, 13460.663483412993, 12855.485780568673, 13115.147515411467, 13169.868301316865, 13238.404271807984, 13283.601491955256, 12834.494747634042, 12900.253169574235, 13220.338983050617, 13318.40179178466, 13374.13068150567, 11280.379579123228, 11315.603953861575, 11548.711282218012, 11631.243969141979, 11801.114916381133, 10750.892491075301, 10890.183236257471, 10910.836337454786, 10925.235233533576, 10958.780824383308], "value": 7504.93552889444, "unit": "score"}], "name": "dromaeo_css", "value": 7486.183957916835, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 113.0, 113.0, 114.0, 116.0, 113.0, 113.0, 113.0, 112.0], "value": 113.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 118.0, 121.0, 158.0, 116.0, 118.0, 117.0, 117.0, 142.0, 119.0], "value": 127.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 159.0, 159.0, 159.0, 160.0, 159.0, 161.0, 163.0, 163.0, 160.0], "value": 160.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 76.0, 121.0, 76.0, 77.0, 75.0, 77.0, 91.0, 75.0], "value": 81.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 111.0, 83.0, 83.0, 83.0, 100.0, 83.0, 83.0, 112.0], "value": 90.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 145.0, 147.0, 146.0, 146.0, 146.0, 146.0, 146.0, 145.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 95.0, 96.0, 96.0, 96.0, 95.0, 96.0, 95.0, 96.0], "value": 95.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 49.0, 50.0, 48.0, 50.0, 51.0, 50.0, 50.0, 50.0, 49.0], "value": 49.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 65.0, 63.0, 64.0, 63.0, 64.0, 64.0, 64.0, 63.0], "value": 63.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 81.0, 80.0, 78.0, 77.0, 85.0, 77.0, 76.0, 75.0, 79.0], "value": 78.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 130.0, 126.0, 124.0, 124.0, 121.0, 120.0, 124.0, 159.0, 125.0], "value": 127.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 158.0, 159.0, 159.0, 158.0, 163.0, 157.0, 158.0, 156.0, 165.0], "value": 159.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 61.0, 60.0, 60.0, 62.0, 61.0, 61.0, 59.0, 61.0], "value": 60.8, "unit": "score"}], "name": "kraken", "value": 1471.7, "alertThreshold": 2.0}]} 17:47:35 INFO - Return code: 0 17:47:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:47:35 ERROR - # TBPL SUCCESS # 17:47:35 INFO - Running post-action listener: _resource_record_post_action 17:47:35 INFO - [mozharness: 2017-02-10 01:47:35.916568Z] Finished run-tests step (success) 17:47:35 INFO - Running post-run listener: _resource_record_post_run 17:47:35 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 262144; Write bytes: 369160192; Read time: 16; Write time: 293352 17:47:35 INFO - TinderboxPrint: CPU usage
13.0% 17:47:35 INFO - TinderboxPrint: I/O read bytes / time
262,144 / 16 17:47:35 INFO - TinderboxPrint: I/O write bytes / time
369,160,192 / 293,352 17:47:35 INFO - TinderboxPrint: CPU idle
8,264.6 (86.9%) 17:47:35 INFO - TinderboxPrint: CPU user
1,194.9 (12.6%) 17:47:35 INFO - TinderboxPrint: Swap in / out
0 / 0 17:47:35 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 116334592; Read time: 0; Write time: 116220 17:47:36 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 262144; Write bytes: 252825600; Read time: 16; Write time: 177132 17:47:36 INFO - Running post-run listener: _upload_blobber_files 17:47:36 INFO - Blob upload gear active. 17:47:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:47:36 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 17:47:36 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', 'Mozilla-Aurora-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:47:36 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 Mozilla-Aurora-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:47:36 INFO - (blobuploader) - INFO - Open directory for files ... 17:47:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:47:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:47:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:47:39 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:47:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:47:39 INFO - (blobuploader) - INFO - Done attempting. 17:47:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:47:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:47:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:47:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:47:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:47:40 INFO - (blobuploader) - INFO - Done attempting. 17:47:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:47:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:47:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:47:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:47:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:47:41 INFO - (blobuploader) - INFO - Done attempting. 17:47:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:47:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:47:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:47:42 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:47:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:47:42 INFO - (blobuploader) - INFO - Done attempting. 17:47:42 INFO - (blobuploader) - INFO - Iteration through files over. 17:47:42 INFO - Return code: 0 17:47:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:47:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:47:42 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/a1b123da754ecbe0975a5ca7c0eaac85398e908e6724335df04b3bb31c9c5db23dafc2ba9544814b960cd81f6a1c5c01cf32b6166a9b5e994ed2edb3b1d9aeae", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/9c3bdabc7908e0a99f033e859437d3ac67bda19254a556715db34782fe17739a12414a6a12bb3ad52bf8354686bade9098f82be71966d91afc5d8abe35ec3733", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/985026b809e9ef36a1747a882a07dcce078aa546f1303b80581a0eb935fa90d85496ecf2f75c6b1c977ef736323f13ea7da6fa807e263fc7305bfc3e938ab8e6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/8caae0d6e9fe2b2c200023e3dd8231775a42cc9baafc8d46fc3d016c49a496fd7abc4e1d9751e859a281d1dd6d65d332ffd3b0a5c1d1ac69d8bd1a56484883c9"} 17:47:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:47:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:47:42 INFO - Contents: 17:47:42 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/a1b123da754ecbe0975a5ca7c0eaac85398e908e6724335df04b3bb31c9c5db23dafc2ba9544814b960cd81f6a1c5c01cf32b6166a9b5e994ed2edb3b1d9aeae", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/9c3bdabc7908e0a99f033e859437d3ac67bda19254a556715db34782fe17739a12414a6a12bb3ad52bf8354686bade9098f82be71966d91afc5d8abe35ec3733", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/985026b809e9ef36a1747a882a07dcce078aa546f1303b80581a0eb935fa90d85496ecf2f75c6b1c977ef736323f13ea7da6fa807e263fc7305bfc3e938ab8e6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/8caae0d6e9fe2b2c200023e3dd8231775a42cc9baafc8d46fc3d016c49a496fd7abc4e1d9751e859a281d1dd6d65d332ffd3b0a5c1d1ac69d8bd1a56484883c9"} 17:47:42 INFO - Running post-run listener: copy_logs_to_upload_dir 17:47:42 INFO - Copying logs to upload dir... 17:47:42 INFO - mkdir: /builds/slave/test/build/upload/logs 17:47:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1236.817735 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2017-02-09 17:47:42.448050) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-09 17:47:42.452053) ========= 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=199fb4c72b90437b990b419b000001c5-1486690023.617762-1245543442 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/a1b123da754ecbe0975a5ca7c0eaac85398e908e6724335df04b3bb31c9c5db23dafc2ba9544814b960cd81f6a1c5c01cf32b6166a9b5e994ed2edb3b1d9aeae", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/9c3bdabc7908e0a99f033e859437d3ac67bda19254a556715db34782fe17739a12414a6a12bb3ad52bf8354686bade9098f82be71966d91afc5d8abe35ec3733", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/985026b809e9ef36a1747a882a07dcce078aa546f1303b80581a0eb935fa90d85496ecf2f75c6b1c977ef736323f13ea7da6fa807e263fc7305bfc3e938ab8e6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/8caae0d6e9fe2b2c200023e3dd8231775a42cc9baafc8d46fc3d016c49a496fd7abc4e1d9751e859a281d1dd6d65d332ffd3b0a5c1d1ac69d8bd1a56484883c9"} build_url:https://queue.taskcluster.net/v1/task/Tn4IGwBxS7669Hnno10MvQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011257 build_url: 'https://queue.taskcluster.net/v1/task/Tn4IGwBxS7669Hnno10MvQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/a1b123da754ecbe0975a5ca7c0eaac85398e908e6724335df04b3bb31c9c5db23dafc2ba9544814b960cd81f6a1c5c01cf32b6166a9b5e994ed2edb3b1d9aeae", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/9c3bdabc7908e0a99f033e859437d3ac67bda19254a556715db34782fe17739a12414a6a12bb3ad52bf8354686bade9098f82be71966d91afc5d8abe35ec3733", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/985026b809e9ef36a1747a882a07dcce078aa546f1303b80581a0eb935fa90d85496ecf2f75c6b1c977ef736323f13ea7da6fa807e263fc7305bfc3e938ab8e6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/8caae0d6e9fe2b2c200023e3dd8231775a42cc9baafc8d46fc3d016c49a496fd7abc4e1d9751e859a281d1dd6d65d332ffd3b0a5c1d1ac69d8bd1a56484883c9"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-09 17:47:42.477109) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 17:47:42.477436) ========= 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=199fb4c72b90437b990b419b000001c5-1486690023.617762-1245543442 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004479 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 17:47:42.559670) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-09 17:47:42.560017) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-09 17:47:42.560345) ========= ========= Total master_lag: 0.15 =========