builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-048 starttime: 1494371501.99 results: success (0) revision: 4b8939ed128172fcd3433ee25f336a8c1ba77ab0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-09 16:11:41.988374) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-09 16:11:41.988834) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-09 16:11:41.989106) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-09 16:11:42.003654) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-09 16:11:42.003914) ========= 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=fa44defc139c4ba8574fe040000001db-1494371501.601023-989996121 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-05-09 16:11:42-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 37.4M=0s 2017-05-09 16:11:42 (37.4 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.111203 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-09 16:11:42.129736) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-09 16:11:42.130043) ========= 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=fa44defc139c4ba8574fe040000001db-1494371501.601023-989996121 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.023002 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-09 16:11:42.182220) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-09 16:11:42.185609) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 4b8939ed128172fcd3433ee25f336a8c1ba77ab0 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 4b8939ed128172fcd3433ee25f336a8c1ba77ab0 --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=fa44defc139c4ba8574fe040000001db-1494371501.601023-989996121 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-05-09 16:11:42,786 truncating revision to first 12 chars 2017-05-09 16:11:42,786 Setting DEBUG logging. 2017-05-09 16:11:42,786 attempt 1/10 2017-05-09 16:11:42,786 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/4b8939ed1281?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-09 16:11:43,206 unpacking tar archive at: mozilla-central-4b8939ed1281/testing/mozharness/ program finished with exit code 0 elapsedTime=0.756217 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-09 16:11:42.955249) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-09 16:11:42.958582) ========= script_repo_revision: 4b8939ed128172fcd3433ee25f336a8c1ba77ab0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-09 16:11:42.958943) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-09 16:11:42.959205) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-09 16:11:42.969559) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2017-05-09 16:11:42.969821) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=fa44defc139c4ba8574fe040000001db-1494371501.601023-989996121 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:11:43 INFO - MultiFileLogger online at 20170509 16:11:43 in /builds/slave/test 16:11:43 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 16:11:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:11:43 INFO - {'append_to_log': False, 16:11:43 INFO - 'base_work_dir': '/builds/slave/test', 16:11:43 INFO - 'blob_upload_branch': 'Firefox', 16:11:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:11:43 INFO - 'branch': 'Firefox', 16:11:43 INFO - 'buildbot_json_path': 'buildprops.json', 16:11:43 INFO - 'config_files': ('talos/linux_config.py',), 16:11:43 INFO - 'default_actions': ('clobber', 16:11:43 INFO - 'read-buildbot-config', 16:11:43 INFO - 'download-and-extract', 16:11:43 INFO - 'populate-webroot', 16:11:43 INFO - 'create-virtualenv', 16:11:43 INFO - 'install', 16:11:43 INFO - 'run-tests'), 16:11:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:11:43 INFO - 'download_minidump_stackwalk': True, 16:11:43 INFO - 'download_symbols': 'ondemand', 16:11:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:11:43 INFO - 'tooltool.py': '/tools/tooltool.py', 16:11:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:11:43 INFO - '/tools/misc-python/virtualenv.py')}, 16:11:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:11:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:11:43 INFO - 'gecko_profile': False, 16:11:43 INFO - 'gecko_profile_interval': 0, 16:11:43 INFO - 'installer_path': 'installer.exe', 16:11:43 INFO - 'log_level': 'info', 16:11:43 INFO - 'log_name': 'talos', 16:11:43 INFO - 'log_to_console': True, 16:11:43 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:11:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:11:43 INFO - 'opt_config_files': (), 16:11:43 INFO - 'pip_index': False, 16:11:43 INFO - 'suite': 'dromaeojs', 16:11:43 INFO - 'system_bits': '32', 16:11:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:11:43 INFO - 'title': 'talos-linux64-ix-048', 16:11:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:11:43 INFO - 'use_talos_json': True, 16:11:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:11:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:11:43 INFO - 'work_dir': 'build'} 16:11:43 INFO - [mozharness: 2017-05-09 23:11:43.668479Z] Running clobber step. 16:11:43 INFO - Running pre-action listener: _resource_record_pre_action 16:11:43 INFO - Running main action method: clobber 16:11:43 INFO - rmtree: /builds/slave/test/build 16:11:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:11:45 INFO - Running post-action listener: _resource_record_post_action 16:11:45 INFO - [mozharness: 2017-05-09 23:11:45.246621Z] Finished clobber step (success) 16:11:45 INFO - [mozharness: 2017-05-09 23:11:45.246720Z] Running read-buildbot-config step. 16:11:45 INFO - Running pre-action listener: _resource_record_pre_action 16:11:45 INFO - Running main action method: read_buildbot_config 16:11:45 INFO - Using buildbot properties: 16:11:45 INFO - { 16:11:45 INFO - "project": "", 16:11:45 INFO - "product": "firefox", 16:11:45 INFO - "who": "asasaki@mozilla.com", 16:11:45 INFO - "installer_path": "public/build/target.tar.bz2", 16:11:45 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 16:11:45 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 16:11:45 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 16:11:45 INFO - "stage_platform": "linux64-pgo", 16:11:45 INFO - "basedir": "/builds/slave/test", 16:11:45 INFO - "buildnumber": 189, 16:11:45 INFO - "platform": "ubuntu64_hw", 16:11:45 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 16:11:45 INFO - "slavebuilddir": "test", 16:11:45 INFO - "taskId": "QCuJOwFYQo2CWqOsQJrdVw", 16:11:45 INFO - "branch": "mozilla-central", 16:11:45 INFO - "script_repo_revision": "production", 16:11:45 INFO - "revision": "4b8939ed128172fcd3433ee25f336a8c1ba77ab0", 16:11:45 INFO - "slavename": "talos-linux64-ix-048", 16:11:45 INFO - "repo_path": "mozilla-central" 16:11:45 INFO - } 16:11:45 INFO - Finding installer, test and symbols from parent task. 16:11:45 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QCuJOwFYQo2CWqOsQJrdVw'}, attempt #1 16:11:45 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QCuJOwFYQo2CWqOsQJrdVw'}, attempt #1 16:11:46 INFO - Task dependencies: HG-JkYyNT7CpAr__TPkPxg 16:11:46 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HG-JkYyNT7CpAr__TPkPxg'}, attempt #1 16:11:46 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/HG-JkYyNT7CpAr__TPkPxg/artifacts/public/build/target.tar.bz2 16:11:46 INFO - Set test_url: https://queue.taskcluster.net/v1/task/HG-JkYyNT7CpAr__TPkPxg/artifacts/public/build/test_packages.json 16:11:46 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/HG-JkYyNT7CpAr__TPkPxg/artifacts/public/build/target.crashreporter-symbols.zip 16:11:46 INFO - Running post-action listener: _resource_record_post_action 16:11:46 INFO - [mozharness: 2017-05-09 23:11:46.621396Z] Finished read-buildbot-config step (success) 16:11:46 INFO - [mozharness: 2017-05-09 23:11:46.621614Z] Running download-and-extract step. 16:11:46 INFO - Running pre-action listener: _resource_record_pre_action 16:11:46 INFO - Running main action method: download_and_extract 16:11:46 INFO - mkdir: /builds/slave/test/build/tests 16:11:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:11:46 INFO - trying https://queue.taskcluster.net/v1/task/HG-JkYyNT7CpAr__TPkPxg/artifacts/public/build/target.test_packages.json 16:11:46 INFO - Downloading https://queue.taskcluster.net/v1/task/HG-JkYyNT7CpAr__TPkPxg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 16:11:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HG-JkYyNT7CpAr__TPkPxg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 16:11:47 INFO - Downloaded 986 bytes. 16:11:47 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 16:11:47 INFO - Using the following test package requirements: 16:11:47 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 16:11:47 INFO - u'common': [u'target.common.tests.zip'], 16:11:47 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 16:11:47 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 16:11:47 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 16:11:47 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 16:11:47 INFO - u'mozbase': [u'target.common.tests.zip'], 16:11:47 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 16:11:47 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 16:11:47 INFO - u'web-platform': [u'target.common.tests.zip', 16:11:47 INFO - u'target.web-platform.tests.zip'], 16:11:47 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 16:11:47 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 16:11:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HG-JkYyNT7CpAr__TPkPxg/artifacts/public/build/target.common.tests.zip 16:11:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HG-JkYyNT7CpAr__TPkPxg/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:11:47 INFO - Fetch https://queue.taskcluster.net/v1/task/HG-JkYyNT7CpAr__TPkPxg/artifacts/public/build/target.common.tests.zip into memory 16:11:48 INFO - Content-Length response header: 60568224 16:11:48 INFO - Bytes received: 60568224 16:11:58 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 16:11:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HG-JkYyNT7CpAr__TPkPxg/artifacts/public/build/target.common.tests.zip 16:11:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HG-JkYyNT7CpAr__TPkPxg/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:11:58 INFO - Fetch https://queue.taskcluster.net/v1/task/HG-JkYyNT7CpAr__TPkPxg/artifacts/public/build/target.common.tests.zip into memory 16:11:59 INFO - Content-Length response header: 60568224 16:11:59 INFO - Bytes received: 60568224 16:12:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HG-JkYyNT7CpAr__TPkPxg/artifacts/public/build/target.talos.tests.zip 16:12:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HG-JkYyNT7CpAr__TPkPxg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 16:12:10 INFO - Fetch https://queue.taskcluster.net/v1/task/HG-JkYyNT7CpAr__TPkPxg/artifacts/public/build/target.talos.tests.zip into memory 16:12:10 INFO - Content-Length response header: 14409362 16:12:10 INFO - Bytes received: 14409362 16:12:11 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')]} 16:12:11 INFO - trying https://queue.taskcluster.net/v1/task/HG-JkYyNT7CpAr__TPkPxg/artifacts/public/build/target.tar.bz2 16:12:11 INFO - Downloading https://queue.taskcluster.net/v1/task/HG-JkYyNT7CpAr__TPkPxg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 16:12:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HG-JkYyNT7CpAr__TPkPxg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 16:12:12 INFO - Downloaded 68531681 bytes. 16:12:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HG-JkYyNT7CpAr__TPkPxg/artifacts/public/build/target.tar.bz2 16:12:12 INFO - mkdir: /builds/slave/test/properties 16:12:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:12:12 INFO - Writing to file /builds/slave/test/properties/build_url 16:12:12 INFO - Contents: 16:12:12 INFO - build_url:https://queue.taskcluster.net/v1/task/HG-JkYyNT7CpAr__TPkPxg/artifacts/public/build/target.tar.bz2 16:12:12 INFO - Running post-action listener: _resource_record_post_action 16:12:12 INFO - Running post-action listener: set_extra_try_arguments 16:12:12 INFO - [mozharness: 2017-05-09 23:12:12.361716Z] Finished download-and-extract step (success) 16:12:12 INFO - [mozharness: 2017-05-09 23:12:12.361813Z] Running populate-webroot step. 16:12:12 INFO - Running pre-action listener: _resource_record_pre_action 16:12:12 INFO - Running main action method: populate_webroot 16:12:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:12:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:12:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:12:12 INFO - '--tppagecycles', 16:12:12 INFO - '7'], 16:12:12 INFO - 'tests': ['tsvgm']}}, 16:12:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:12:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:12:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:12:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:12:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:12:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:12:12 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 16:12:12 INFO - 'talos_options': ['--disable-e10s'], 16:12:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:12:12 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:12:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:12:12 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 16:12:12 INFO - 'talos_options': ['--disable-e10s'], 16:12:12 INFO - 'tests': ['damp', 'tps']}, 16:12:12 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:12:12 INFO - 'tests': ['damp', 'tps']}, 16:12:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:12:12 INFO - 'tests': ['dromaeo_dom']}, 16:12:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:12:12 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:12:12 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 16:12:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 16:12:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:12:12 INFO - 'tests': ['a11yr', 16:12:12 INFO - 'ts_paint', 16:12:12 INFO - 'tpaint', 16:12:12 INFO - 'sessionrestore', 16:12:12 INFO - 'sessionrestore_no_auto_restore', 16:12:12 INFO - 'tabpaint']}, 16:12:12 INFO - 'other-e10s': {'tests': ['a11yr', 16:12:12 INFO - 'ts_paint', 16:12:12 INFO - 'tpaint', 16:12:12 INFO - 'sessionrestore', 16:12:12 INFO - 'sessionrestore_no_auto_restore', 16:12:12 INFO - 'tabpaint']}, 16:12:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:12:12 INFO - 'ts_paint', 16:12:12 INFO - 'tpaint', 16:12:12 INFO - 'sessionrestore', 16:12:12 INFO - 'sessionrestore_no_auto_restore', 16:12:12 INFO - 'tabpaint']}, 16:12:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:12:12 INFO - 'ts_paint', 16:12:12 INFO - 'tpaint', 16:12:12 INFO - 'sessionrestore', 16:12:12 INFO - 'sessionrestore_no_auto_restore', 16:12:12 INFO - 'tabpaint']}, 16:12:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:12:12 INFO - 'tests': ['a11yr', 16:12:12 INFO - 'ts_paint', 16:12:12 INFO - 'tpaint', 16:12:12 INFO - 'sessionrestore', 16:12:12 INFO - 'sessionrestore_no_auto_restore', 16:12:12 INFO - 'tabpaint']}, 16:12:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:12:12 INFO - 'tests': ['a11yr', 16:12:12 INFO - 'ts_paint', 16:12:12 INFO - 'tpaint', 16:12:12 INFO - 'sessionrestore', 16:12:12 INFO - 'sessionrestore_no_auto_restore', 16:12:12 INFO - 'tabpaint']}, 16:12:12 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 16:12:12 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 16:12:12 INFO - 'bloom_basic_ref']}, 16:12:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:12:12 INFO - 'tests': ['tsvgx', 16:12:12 INFO - 'tsvgr_opacity', 16:12:12 INFO - 'tart', 16:12:12 INFO - 'tscrollx', 16:12:12 INFO - 'cart', 16:12:12 INFO - 'tsvg_static']}, 16:12:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:12:12 INFO - 'tsvgr_opacity', 16:12:12 INFO - 'tart', 16:12:12 INFO - 'tscrollx', 16:12:12 INFO - 'cart', 16:12:12 INFO - 'tsvg_static']}, 16:12:12 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 16:12:12 INFO - 'talos_options': ['--disable-e10s'], 16:12:12 INFO - 'tests': ['tp5o']}, 16:12:12 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:12:12 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 16:12:12 INFO - 'talos_options': ['--disable-e10s', 16:12:12 INFO - '--xperf_path', 16:12:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:12:12 INFO - 'tests': ['tp5n']}, 16:12:12 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:12:12 INFO - 'talos_options': ['--xperf_path', 16:12:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:12:12 INFO - 'tests': ['tp5n']}}, 16:12:12 INFO - 'talos.zip': {'path': '', 16:12:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:12:12 INFO - Running post-action listener: _resource_record_post_action 16:12:12 INFO - [mozharness: 2017-05-09 23:12:12.375483Z] Finished populate-webroot step (success) 16:12:12 INFO - [mozharness: 2017-05-09 23:12:12.375573Z] Running create-virtualenv step. 16:12:12 INFO - Running pre-action listener: _resource_record_pre_action 16:12:12 INFO - Running main action method: create_virtualenv 16:12:12 INFO - Creating virtualenv /builds/slave/test/build/venv 16:12:12 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 16:12:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:12:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:12:12 INFO - Using real prefix '/usr' 16:12:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:12:12 INFO - Installing distribute.............................................................................................................................................................................................done. 16:12:14 INFO - Installing pip.................done. 16:12:14 INFO - Return code: 0 16:12:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:12:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:12:15 INFO - Reading from file tmpfile_stdout 16:12:15 INFO - Output received: 16:12:15 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) 16:12:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:12:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:12:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:12:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:15 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 0x12471f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1413a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15b9770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b6a30>, '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 0x15b77f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15b7ca0>, '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': 'fa44defc139c4ba8574fe040000001db-1494371501.601023-989996121', '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 16:12:15 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 16:12:15 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 16:12:15 INFO - Using env: {'DISPLAY': ':0', 16:12:15 INFO - 'HOME': '/home/cltbld', 16:12:15 INFO - 'LANG': 'en_US.UTF-8', 16:12:15 INFO - 'LANGUAGE': 'en_US:en', 16:12:15 INFO - 'LOGNAME': 'cltbld', 16:12:15 INFO - 'MAIL': '/var/mail/cltbld', 16:12:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:15 INFO - 'MOZ_NO_REMOTE': '1', 16:12:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:15 INFO - 'NO_EM_RESTART': '1', 16:12:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:15 INFO - 'PWD': '/builds/slave/test', 16:12:15 INFO - 'SHELL': '/bin/bash', 16:12:15 INFO - 'SHLVL': '1', 16:12:15 INFO - 'TERM': 'linux', 16:12:15 INFO - 'TMOUT': '86400', 16:12:15 INFO - 'USER': 'cltbld', 16:12:15 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1494371501.601023-989996121', 16:12:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:15 INFO - '_': '/tools/buildbot/bin/python'} 16:12:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:15 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 16:12:15 INFO - Cleaning up... 16:12:15 INFO - Return code: 0 16:12:15 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:12:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:12:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:12:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:15 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 0x12471f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1413a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15b9770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b6a30>, '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 0x15b77f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15b7ca0>, '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': 'fa44defc139c4ba8574fe040000001db-1494371501.601023-989996121', '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 16:12:15 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 16:12:15 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 16:12:15 INFO - Using env: {'DISPLAY': ':0', 16:12:15 INFO - 'HOME': '/home/cltbld', 16:12:15 INFO - 'LANG': 'en_US.UTF-8', 16:12:15 INFO - 'LANGUAGE': 'en_US:en', 16:12:15 INFO - 'LOGNAME': 'cltbld', 16:12:15 INFO - 'MAIL': '/var/mail/cltbld', 16:12:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:15 INFO - 'MOZ_NO_REMOTE': '1', 16:12:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:15 INFO - 'NO_EM_RESTART': '1', 16:12:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:15 INFO - 'PWD': '/builds/slave/test', 16:12:15 INFO - 'SHELL': '/bin/bash', 16:12:15 INFO - 'SHLVL': '1', 16:12:15 INFO - 'TERM': 'linux', 16:12:15 INFO - 'TMOUT': '86400', 16:12:15 INFO - 'USER': 'cltbld', 16:12:15 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1494371501.601023-989996121', 16:12:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:15 INFO - '_': '/tools/buildbot/bin/python'} 16:12:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:15 INFO - Downloading/unpacking psutil>=3.1.1 16:12:15 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 16:12:15 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 16:12:15 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 16:12:15 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 16:12:15 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 16:12:15 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 16:12:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:12:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:12:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:12:16 INFO - Installing collected packages: psutil 16:12:16 INFO - Running setup.py install for psutil 16:12:16 INFO - building 'psutil._psutil_linux' extension 16:12:16 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 16:12:17 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 16:12:17 INFO - building 'psutil._psutil_posix' extension 16:12:17 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 16:12:17 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 16:12:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:12:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:12:17 INFO - Successfully installed psutil 16:12:17 INFO - Cleaning up... 16:12:17 INFO - Return code: 0 16:12:17 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:12:17 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')]} 16:12:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:17 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')]} 16:12:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:17 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 0x12471f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1413a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15b9770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b6a30>, '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 0x15b77f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15b7ca0>, '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': 'fa44defc139c4ba8574fe040000001db-1494371501.601023-989996121', '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 16:12:17 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 16:12:17 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 16:12:17 INFO - Using env: {'DISPLAY': ':0', 16:12:17 INFO - 'HOME': '/home/cltbld', 16:12:17 INFO - 'LANG': 'en_US.UTF-8', 16:12:17 INFO - 'LANGUAGE': 'en_US:en', 16:12:17 INFO - 'LOGNAME': 'cltbld', 16:12:17 INFO - 'MAIL': '/var/mail/cltbld', 16:12:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:17 INFO - 'MOZ_NO_REMOTE': '1', 16:12:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:17 INFO - 'NO_EM_RESTART': '1', 16:12:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:17 INFO - 'PWD': '/builds/slave/test', 16:12:17 INFO - 'SHELL': '/bin/bash', 16:12:17 INFO - 'SHLVL': '1', 16:12:17 INFO - 'TERM': 'linux', 16:12:17 INFO - 'TMOUT': '86400', 16:12:17 INFO - 'USER': 'cltbld', 16:12:17 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1494371501.601023-989996121', 16:12:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:17 INFO - '_': '/tools/buildbot/bin/python'} 16:12:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:12:17 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 16:12:17 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 16:12:17 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 16:12:17 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 16:12:17 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 16:12:17 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 16:12:18 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:12:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:12:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:12:18 INFO - Installing collected packages: mozsystemmonitor 16:12:18 INFO - Running setup.py install for mozsystemmonitor 16:12:18 INFO - Successfully installed mozsystemmonitor 16:12:18 INFO - Cleaning up... 16:12:18 INFO - Return code: 0 16:12:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:12:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:12:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:12:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:18 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 0x12471f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1413a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15b9770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b6a30>, '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 0x15b77f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15b7ca0>, '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': 'fa44defc139c4ba8574fe040000001db-1494371501.601023-989996121', '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 16:12:18 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 16:12:18 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 16:12:18 INFO - Using env: {'DISPLAY': ':0', 16:12:18 INFO - 'HOME': '/home/cltbld', 16:12:18 INFO - 'LANG': 'en_US.UTF-8', 16:12:18 INFO - 'LANGUAGE': 'en_US:en', 16:12:18 INFO - 'LOGNAME': 'cltbld', 16:12:18 INFO - 'MAIL': '/var/mail/cltbld', 16:12:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:18 INFO - 'MOZ_NO_REMOTE': '1', 16:12:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:18 INFO - 'NO_EM_RESTART': '1', 16:12:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:18 INFO - 'PWD': '/builds/slave/test', 16:12:18 INFO - 'SHELL': '/bin/bash', 16:12:18 INFO - 'SHLVL': '1', 16:12:18 INFO - 'TERM': 'linux', 16:12:18 INFO - 'TMOUT': '86400', 16:12:18 INFO - 'USER': 'cltbld', 16:12:18 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1494371501.601023-989996121', 16:12:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:18 INFO - '_': '/tools/buildbot/bin/python'} 16:12:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:19 INFO - Downloading/unpacking jsonschema==2.5.1 16:12:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:12:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:12:20 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 16:12:20 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 16:12:20 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 16:12:20 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 16:12:20 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 16:12:20 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 16:12:20 INFO - Downloading functools32-3.2.3-2.tar.gz 16:12:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:12:20 INFO - warning: no files found matching '*.txt' 16:12:20 INFO - no previously-included directories found matching 'build' 16:12:20 INFO - no previously-included directories found matching 'dist' 16:12:20 INFO - no previously-included directories found matching '.git*' 16:12:20 INFO - Installing collected packages: jsonschema, functools32 16:12:20 INFO - Running setup.py install for functools32 16:12:20 INFO - warning: no files found matching '*.txt' 16:12:20 INFO - no previously-included directories found matching 'build' 16:12:20 INFO - no previously-included directories found matching 'dist' 16:12:20 INFO - no previously-included directories found matching '.git*' 16:12:20 INFO - Successfully installed jsonschema functools32 16:12:20 INFO - Cleaning up... 16:12:20 INFO - Return code: 0 16:12:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:12: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')]} 16:12:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12: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')]} 16:12:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:20 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 0x12471f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1413a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15b9770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b6a30>, '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 0x15b77f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15b7ca0>, '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': 'fa44defc139c4ba8574fe040000001db-1494371501.601023-989996121', '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 16:12:20 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 16:12:20 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 16:12:20 INFO - Using env: {'DISPLAY': ':0', 16:12:20 INFO - 'HOME': '/home/cltbld', 16:12:20 INFO - 'LANG': 'en_US.UTF-8', 16:12:20 INFO - 'LANGUAGE': 'en_US:en', 16:12:20 INFO - 'LOGNAME': 'cltbld', 16:12:20 INFO - 'MAIL': '/var/mail/cltbld', 16:12:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:20 INFO - 'MOZ_NO_REMOTE': '1', 16:12:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:20 INFO - 'NO_EM_RESTART': '1', 16:12:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:20 INFO - 'PWD': '/builds/slave/test', 16:12:20 INFO - 'SHELL': '/bin/bash', 16:12:20 INFO - 'SHLVL': '1', 16:12:20 INFO - 'TERM': 'linux', 16:12:20 INFO - 'TMOUT': '86400', 16:12:20 INFO - 'USER': 'cltbld', 16:12:20 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1494371501.601023-989996121', 16:12:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:20 INFO - '_': '/tools/buildbot/bin/python'} 16:12:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:12:20 INFO - Cleaning up... 16:12:20 INFO - Return code: 0 16:12:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:12: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')]} 16:12:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12: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')]} 16:12:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:20 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 0x12471f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1413a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15b9770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b6a30>, '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 0x15b77f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15b7ca0>, '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': 'fa44defc139c4ba8574fe040000001db-1494371501.601023-989996121', '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 16:12:20 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 16:12:20 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 16:12:20 INFO - Using env: {'DISPLAY': ':0', 16:12:20 INFO - 'HOME': '/home/cltbld', 16:12:20 INFO - 'LANG': 'en_US.UTF-8', 16:12:20 INFO - 'LANGUAGE': 'en_US:en', 16:12:20 INFO - 'LOGNAME': 'cltbld', 16:12:20 INFO - 'MAIL': '/var/mail/cltbld', 16:12:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:20 INFO - 'MOZ_NO_REMOTE': '1', 16:12:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:20 INFO - 'NO_EM_RESTART': '1', 16:12:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:20 INFO - 'PWD': '/builds/slave/test', 16:12:20 INFO - 'SHELL': '/bin/bash', 16:12:20 INFO - 'SHLVL': '1', 16:12:20 INFO - 'TERM': 'linux', 16:12:20 INFO - 'TMOUT': '86400', 16:12:20 INFO - 'USER': 'cltbld', 16:12:20 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1494371501.601023-989996121', 16:12:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:20 INFO - '_': '/tools/buildbot/bin/python'} 16:12:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:21 INFO - Downloading/unpacking blobuploader==1.2.4 16:12:21 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 16:12:21 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 16:12:21 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 16:12:21 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 16:12:21 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 16:12:21 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 16:12:22 INFO - Downloading blobuploader-1.2.4.tar.gz 16:12:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:12:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:12:22 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 16:12:22 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 16:12:22 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 16:12:22 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 16:12:22 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 16:12:22 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 16:12:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:12:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:12:22 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 16:12:22 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 16:12:22 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 16:12:22 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 16:12:22 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 16:12:22 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 16:12:22 INFO - Downloading docopt-0.6.1.tar.gz 16:12:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:12:22 INFO - Installing collected packages: blobuploader, requests, docopt 16:12:22 INFO - Running setup.py install for blobuploader 16:12:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:12:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:12:22 INFO - Running setup.py install for requests 16:12:23 INFO - Running setup.py install for docopt 16:12:23 INFO - Successfully installed blobuploader requests docopt 16:12:23 INFO - Cleaning up... 16:12:23 INFO - Return code: 0 16:12:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:12:23 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')]} 16:12:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:23 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')]} 16:12:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:23 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 0x12471f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1413a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15b9770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b6a30>, '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 0x15b77f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15b7ca0>, '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': 'fa44defc139c4ba8574fe040000001db-1494371501.601023-989996121', '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 16:12:23 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 16:12:23 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 16:12:23 INFO - Using env: {'DISPLAY': ':0', 16:12:23 INFO - 'HOME': '/home/cltbld', 16:12:23 INFO - 'LANG': 'en_US.UTF-8', 16:12:23 INFO - 'LANGUAGE': 'en_US:en', 16:12:23 INFO - 'LOGNAME': 'cltbld', 16:12:23 INFO - 'MAIL': '/var/mail/cltbld', 16:12:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:23 INFO - 'MOZ_NO_REMOTE': '1', 16:12:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:23 INFO - 'NO_EM_RESTART': '1', 16:12:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:23 INFO - 'PWD': '/builds/slave/test', 16:12:23 INFO - 'SHELL': '/bin/bash', 16:12:23 INFO - 'SHLVL': '1', 16:12:23 INFO - 'TERM': 'linux', 16:12:23 INFO - 'TMOUT': '86400', 16:12:23 INFO - 'USER': 'cltbld', 16:12:23 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1494371501.601023-989996121', 16:12:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:23 INFO - '_': '/tools/buildbot/bin/python'} 16:12:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:12:23 INFO - Running setup.py (path:/tmp/pip-BLyeVR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:12:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:12:23 INFO - Running setup.py (path:/tmp/pip-Gze6Pr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:12:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:12:23 INFO - Running setup.py (path:/tmp/pip-hf9nqU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:12:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:12:23 INFO - Running setup.py (path:/tmp/pip-xlcPKD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:12:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:12:23 INFO - Running setup.py (path:/tmp/pip-96fc7r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:12:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:12:23 INFO - Running setup.py (path:/tmp/pip-nnae5a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:12:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:12:24 INFO - Running setup.py (path:/tmp/pip-i4TEBU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:12:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:12:24 INFO - Running setup.py (path:/tmp/pip-jckCA_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:12:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:12:24 INFO - Running setup.py (path:/tmp/pip-FgbuDs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:12:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:12:24 INFO - Running setup.py (path:/tmp/pip-bcoTK8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:12:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:12:24 INFO - Running setup.py (path:/tmp/pip-tzQe50-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:12:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:12:24 INFO - Running setup.py (path:/tmp/pip-wdBcZv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:12:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:12:24 INFO - Running setup.py (path:/tmp/pip-gcfVlf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:12:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:12:24 INFO - Running setup.py (path:/tmp/pip-BEPw1D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:12:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:12:24 INFO - Running setup.py (path:/tmp/pip-wz8b3e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:12:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:12:24 INFO - Running setup.py (path:/tmp/pip-eQmQzf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:12:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:12:24 INFO - Running setup.py (path:/tmp/pip-kBPHgo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:12:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:12:24 INFO - Running setup.py install for manifestparser 16:12:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:12:24 INFO - Running setup.py install for mozcrash 16:12:25 INFO - Running setup.py install for mozdebug 16:12:25 INFO - Running setup.py install for mozdevice 16:12:25 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:12:25 INFO - Running setup.py install for mozfile 16:12:25 INFO - Running setup.py install for mozhttpd 16:12:25 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:12:25 INFO - Running setup.py install for mozinfo 16:12:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:12:25 INFO - Running setup.py install for mozInstall 16:12:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:12:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:12:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:12:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:12:25 INFO - Running setup.py install for mozleak 16:12:25 INFO - Running setup.py install for mozlog 16:12:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:12:25 INFO - Running setup.py install for moznetwork 16:12:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:12:25 INFO - Running setup.py install for mozprocess 16:12:26 INFO - Running setup.py install for mozprofile 16:12:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:12:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:12:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:12:26 INFO - Running setup.py install for mozrunner 16:12:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:12:26 INFO - Running setup.py install for mozscreenshot 16:12:26 INFO - Running setup.py install for moztest 16:12:26 INFO - Running setup.py install for mozversion 16:12:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:12:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:12:26 INFO - Cleaning up... 16:12:26 INFO - Return code: 0 16:12:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:12:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:12:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:12:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:26 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 0x12471f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1413a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15b9770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b6a30>, '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 0x15b77f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15b7ca0>, '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': 'fa44defc139c4ba8574fe040000001db-1494371501.601023-989996121', '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 16:12:26 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 16:12:26 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 16:12:26 INFO - Using env: {'DISPLAY': ':0', 16:12:26 INFO - 'HOME': '/home/cltbld', 16:12:26 INFO - 'LANG': 'en_US.UTF-8', 16:12:26 INFO - 'LANGUAGE': 'en_US:en', 16:12:26 INFO - 'LOGNAME': 'cltbld', 16:12:26 INFO - 'MAIL': '/var/mail/cltbld', 16:12:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:26 INFO - 'MOZ_NO_REMOTE': '1', 16:12:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:26 INFO - 'NO_EM_RESTART': '1', 16:12:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:26 INFO - 'PWD': '/builds/slave/test', 16:12:26 INFO - 'SHELL': '/bin/bash', 16:12:26 INFO - 'SHLVL': '1', 16:12:26 INFO - 'TERM': 'linux', 16:12:26 INFO - 'TMOUT': '86400', 16:12:26 INFO - 'USER': 'cltbld', 16:12:26 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1494371501.601023-989996121', 16:12:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:26 INFO - '_': '/tools/buildbot/bin/python'} 16:12:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:12:26 INFO - Running setup.py (path:/tmp/pip-_RljjD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:12:27 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)) 16:12:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:12:27 INFO - Running setup.py (path:/tmp/pip-1VH3o5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:12:27 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)) 16:12:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:12:27 INFO - Running setup.py (path:/tmp/pip-iDymhl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:12:27 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)) 16:12:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:12:27 INFO - Running setup.py (path:/tmp/pip-LbUaf_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:12:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:12:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:12:27 INFO - Running setup.py (path:/tmp/pip-5DzAxn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:12:27 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)) 16:12:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:12:27 INFO - Running setup.py (path:/tmp/pip-Xe9Yu2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:12:27 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)) 16:12:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:12:27 INFO - Running setup.py (path:/tmp/pip-Y8Gma1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:12:27 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)) 16:12:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:12:27 INFO - Running setup.py (path:/tmp/pip-D5EI6Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:12:27 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)) 16:12:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:12:27 INFO - Running setup.py (path:/tmp/pip-1yvwBh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:12:27 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)) 16:12:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:12:27 INFO - Running setup.py (path:/tmp/pip-67Xw1Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:12:27 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)) 16:12:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:12:27 INFO - Running setup.py (path:/tmp/pip-HHJENf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:12:27 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)) 16:12:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:12:27 INFO - Running setup.py (path:/tmp/pip-iRjZF3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:12:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 16:12:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:12:27 INFO - Running setup.py (path:/tmp/pip-ZVL95k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:12:27 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)) 16:12:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:12:27 INFO - Running setup.py (path:/tmp/pip-cTGjN6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:12:27 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)) 16:12:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:12:27 INFO - Running setup.py (path:/tmp/pip-v0bPOL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:12:28 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)) 16:12:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:12:28 INFO - Running setup.py (path:/tmp/pip-JjuEFM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:12:28 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)) 16:12:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:12:28 INFO - Running setup.py (path:/tmp/pip-7qOZyy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:12:28 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)) 16:12:28 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)) 16:12:28 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)) 16:12:28 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)) 16:12:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:12:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:12:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:12:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:29 INFO - Downloading blessings-1.6.tar.gz 16:12:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:12:29 INFO - Installing collected packages: blessings 16:12:29 INFO - Running setup.py install for blessings 16:12:29 INFO - Successfully installed blessings 16:12:29 INFO - Cleaning up... 16:12:29 INFO - Return code: 0 16:12:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:12:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:12:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:12:29 INFO - Reading from file tmpfile_stdout 16:12:29 INFO - Current package versions: 16:12:29 INFO - argparse == 1.2.1 16:12:29 INFO - blessings == 1.6 16:12:29 INFO - blobuploader == 1.2.4 16:12:29 INFO - docopt == 0.6.1 16:12:29 INFO - functools32 == 3.2.3-2 16:12:29 INFO - jsonschema == 2.5.1 16:12:29 INFO - manifestparser == 1.1 16:12:29 INFO - mozInstall == 1.12 16:12:29 INFO - mozcrash == 1.0 16:12:29 INFO - mozdebug == 0.1 16:12:29 INFO - mozdevice == 0.50 16:12:29 INFO - mozfile == 1.2 16:12:29 INFO - mozhttpd == 0.7 16:12:29 INFO - mozinfo == 0.9 16:12:29 INFO - mozleak == 0.1 16:12:29 INFO - mozlog == 3.4 16:12:29 INFO - moznetwork == 0.27 16:12:29 INFO - mozprocess == 0.25 16:12:29 INFO - mozprofile == 0.28 16:12:29 INFO - mozrunner == 6.13 16:12:29 INFO - mozscreenshot == 0.1 16:12:29 INFO - mozsystemmonitor == 0.3 16:12:29 INFO - moztest == 0.8 16:12:29 INFO - mozversion == 1.4 16:12:29 INFO - psutil == 3.1.1 16:12:29 INFO - requests == 1.2.3 16:12:29 INFO - wsgiref == 0.1.2 16:12:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:12: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')]} 16:12:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12: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')]} 16:12:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:29 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 0x12471f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1413a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15b9770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b6a30>, '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 0x15b77f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15b7ca0>, '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': 'fa44defc139c4ba8574fe040000001db-1494371501.601023-989996121', '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 16:12:29 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 16:12:29 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 16:12:29 INFO - Using env: {'DISPLAY': ':0', 16:12:29 INFO - 'HOME': '/home/cltbld', 16:12:29 INFO - 'LANG': 'en_US.UTF-8', 16:12:29 INFO - 'LANGUAGE': 'en_US:en', 16:12:29 INFO - 'LOGNAME': 'cltbld', 16:12:29 INFO - 'MAIL': '/var/mail/cltbld', 16:12:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:29 INFO - 'MOZ_NO_REMOTE': '1', 16:12:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:29 INFO - 'NO_EM_RESTART': '1', 16:12:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:29 INFO - 'PWD': '/builds/slave/test', 16:12:29 INFO - 'SHELL': '/bin/bash', 16:12:29 INFO - 'SHLVL': '1', 16:12:29 INFO - 'TERM': 'linux', 16:12:29 INFO - 'TMOUT': '86400', 16:12:29 INFO - 'USER': 'cltbld', 16:12:29 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1494371501.601023-989996121', 16:12:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:29 INFO - '_': '/tools/buildbot/bin/python'} 16:12:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:29 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)) 16:12:29 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)) 16:12:29 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)) 16:12:29 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)) 16:12:29 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)) 16:12:29 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)) 16:12:29 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)) 16:12:29 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)) 16:12:29 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)) 16:12:29 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)) 16:12:29 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)) 16:12:29 INFO - Cleaning up... 16:12:30 INFO - Return code: 0 16:12:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:12:30 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')]} 16:12:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:30 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')]} 16:12:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:30 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 0x12471f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1413a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15b9770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15b6a30>, '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 0x15b77f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15b7ca0>, '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': 'fa44defc139c4ba8574fe040000001db-1494371501.601023-989996121', '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 16:12:30 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 16:12:30 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 16:12:30 INFO - Using env: {'DISPLAY': ':0', 16:12:30 INFO - 'HOME': '/home/cltbld', 16:12:30 INFO - 'LANG': 'en_US.UTF-8', 16:12:30 INFO - 'LANGUAGE': 'en_US:en', 16:12:30 INFO - 'LOGNAME': 'cltbld', 16:12:30 INFO - 'MAIL': '/var/mail/cltbld', 16:12:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:30 INFO - 'MOZ_NO_REMOTE': '1', 16:12:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:30 INFO - 'NO_EM_RESTART': '1', 16:12:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:30 INFO - 'PWD': '/builds/slave/test', 16:12:30 INFO - 'SHELL': '/bin/bash', 16:12:30 INFO - 'SHLVL': '1', 16:12:30 INFO - 'TERM': 'linux', 16:12:30 INFO - 'TMOUT': '86400', 16:12:30 INFO - 'USER': 'cltbld', 16:12:30 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1494371501.601023-989996121', 16:12:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:30 INFO - '_': '/tools/buildbot/bin/python'} 16:12:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:12:30 INFO - Cleaning up... 16:12:30 INFO - Return code: 0 16:12:30 INFO - Running post-action listener: _resource_record_post_action 16:12:30 INFO - Running post-action listener: _start_resource_monitoring 16:12:30 INFO - Starting resource monitoring. 16:12:30 INFO - [mozharness: 2017-05-09 23:12:30.276513Z] Finished create-virtualenv step (success) 16:12:30 INFO - [mozharness: 2017-05-09 23:12:30.277083Z] Running install step. 16:12:30 INFO - Running pre-action listener: _resource_record_pre_action 16:12:30 INFO - Running main action method: install 16:12:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:12:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:12:30 INFO - Reading from file tmpfile_stdout 16:12:30 INFO - Detecting whether we're running mozinstall >=1.0... 16:12:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:12:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:12:30 INFO - Reading from file tmpfile_stdout 16:12:30 INFO - Output received: 16:12:30 INFO - Usage: mozinstall [options] installer 16:12:30 INFO - Options: 16:12:30 INFO - -h, --help show this help message and exit 16:12:30 INFO - -d DEST, --destination=DEST 16:12:30 INFO - Directory to install application into. [default: 16:12:30 INFO - "/builds/slave/test"] 16:12:30 INFO - --app=APP Application being installed. [default: firefox] 16:12:30 INFO - mkdir: /builds/slave/test/build/application 16:12:30 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'] 16:12:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 16:12:47 INFO - Reading from file tmpfile_stdout 16:12:47 INFO - Output received: 16:12:47 INFO - /builds/slave/test/build/application/firefox/firefox 16:12:47 INFO - Running post-action listener: _resource_record_post_action 16:12:47 INFO - [mozharness: 2017-05-09 23:12:47.821255Z] Finished install step (success) 16:12:47 INFO - [mozharness: 2017-05-09 23:12:47.821524Z] Running run-tests step. 16:12:47 INFO - Running pre-action listener: _resource_record_pre_action 16:12:47 INFO - Running main action method: run_tests 16:12:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:12:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:12:47 INFO - Python 2.7.3 16:12:47 INFO - Return code: 0 16:12:47 INFO - grabbing minidump binary from tooltool 16:12:47 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')]} 16:12:47 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 0x15b6a30>, '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 0x15b77f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15b7ca0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:12:47 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 16:12:47 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 16:12:47 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 16:12:47 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:12:47 INFO - Return code: 0 16:12:47 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:12:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:12:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:12:47 INFO - ENV: RUST_BACKTRACE is now 1 16:12:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:12:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:12:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HG-JkYyNT7CpAr__TPkPxg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-048', '--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 16:12:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/HG-JkYyNT7CpAr__TPkPxg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-048 --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 16:12:47 INFO - Using env: {'DISPLAY': ':0', 16:12:47 INFO - 'HOME': '/home/cltbld', 16:12:47 INFO - 'LANG': 'en_US.UTF-8', 16:12:47 INFO - 'LANGUAGE': 'en_US:en', 16:12:47 INFO - 'LOGNAME': 'cltbld', 16:12:47 INFO - 'MAIL': '/var/mail/cltbld', 16:12:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:12:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:12:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:47 INFO - 'MOZ_NO_REMOTE': '1', 16:12:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:12:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:47 INFO - 'NO_EM_RESTART': '1', 16:12:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:47 INFO - 'PWD': '/builds/slave/test', 16:12:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:12:47 INFO - 'RUST_BACKTRACE': '1', 16:12:47 INFO - 'SHELL': '/bin/bash', 16:12:47 INFO - 'SHLVL': '1', 16:12:47 INFO - 'TERM': 'linux', 16:12:47 INFO - 'TMOUT': '86400', 16:12:47 INFO - 'USER': 'cltbld', 16:12:47 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1494371501.601023-989996121', 16:12:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:47 INFO - '_': '/tools/buildbot/bin/python'} 16:12:47 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HG-JkYyNT7CpAr__TPkPxg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-048', '--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 16:12:48 INFO - mozversion application_buildid: 20170509202905 16:12:48 INFO - mozversion application_changeset: 4b8939ed128172fcd3433ee25f336a8c1ba77ab0 16:12:48 INFO - mozversion application_display_name: Nightly 16:12:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:12:48 INFO - mozversion application_name: Firefox 16:12:48 INFO - mozversion application_remotingname: firefox 16:12:48 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:12:48 INFO - mozversion application_vendor: Mozilla 16:12:48 INFO - mozversion application_version: 55.0a1 16:12:48 INFO - mozversion platform_buildid: 20170509202905 16:12:48 INFO - mozversion platform_changeset: 4b8939ed128172fcd3433ee25f336a8c1ba77ab0 16:12:48 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:12:48 INFO - mozversion platform_version: 55.0a1 16:12:48 INFO - using testdate: 1494371568 16:12:48 INFO - actual date: 1494371568 16:12:48 INFO - starting webserver on 'localhost:52687' 16:12:48 INFO - SUITE-START | Running 2 tests 16:12:48 INFO - TEST-START | dromaeo_css 16:12:48 INFO - operating with platform_type : linux_ 16:12:48 INFO - Initialising browser for dromaeo_css test... 16:12:48 INFO - TEST-INFO | started process 12833 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAitSH9/profile http://localhost:52687/getInfo.html) 16:12:51 INFO - PID 12833 | __metrics Screen width/height:1600/1200 16:12:51 INFO - PID 12833 | colorDepth:24 16:12:51 INFO - PID 12833 | Browser inner width/height: 1024/695 16:12:51 INFO - PID 12833 | __metrics 16:12:52 INFO - PID 12833 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:12:52 INFO - PID 12833 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:12:53 INFO - TEST-INFO | 12833: exit 0 16:12:53 INFO - Browser initialized. 16:12:53 INFO - Running cycle 1/1 for dromaeo_css test... 16:12:53 INFO - TEST-INFO | started process 12930 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAitSH9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:13:05 INFO - PID 12930 | RSS: Main: 144859136 16:13:05 INFO - PID 12930 | 16:16:08 INFO - PID 12930 | Cycle 1(1): loaded http://localhost:52687/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52687/tests/dromaeo/cssquery-ext.html) 16:16:10 INFO - PID 12930 | RSS: Main: 299941888 16:16:10 INFO - PID 12930 | 16:19:12 INFO - PID 12930 | Cycle 1(1): loaded http://localhost:52687/tests/dromaeo/cssquery-ext.html (next: http://localhost:52687/tests/dromaeo/cssquery-jquery.html) 16:19:12 INFO - PID 12930 | RSS: Main: 226549760 16:19:12 INFO - PID 12930 | 16:22:14 INFO - PID 12930 | Cycle 1(1): loaded http://localhost:52687/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52687/tests/dromaeo/cssquery-mootools.html) 16:22:15 INFO - PID 12930 | RSS: Main: 176336896 16:22:15 INFO - PID 12930 | 16:25:16 INFO - PID 12930 | Cycle 1(1): loaded http://localhost:52687/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52687/tests/dromaeo/cssquery-prototype.html) 16:25:17 INFO - PID 12930 | RSS: Main: 178159616 16:25:17 INFO - PID 12930 | 16:28:19 INFO - PID 12930 | Cycle 1(1): loaded http://localhost:52687/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52687/tests/dromaeo/cssquery-yui.html) 16:28:20 INFO - PID 12930 | RSS: Main: 177156096 16:28:20 INFO - PID 12930 | 16:31:22 INFO - PID 12930 | Cycle 1(1): loaded http://localhost:52687/tests/dromaeo/cssquery-yui.html (next: http://localhost:52687/tests/dromaeo/cssquery-dojo.html) 16:31:22 INFO - PID 12930 | RSS: Main: 272216064 16:31:22 INFO - PID 12930 | 16:31:22 INFO - PID 12930 | __start_tp_report 16:31:22 INFO - PID 12930 | _x_x_mozilla_page_load 16:31:22 INFO - PID 12930 | _x_x_mozilla_page_load_details 16:31:22 INFO - PID 12930 | |i|pagename|runs| 16:31:22 INFO - PID 12930 | |0;dojo.html;3391.7456190785697;3409.1988382730055;3424.315136972606;3425.383430982422;3460.0484866661664;5762.222100016496;5883.264591926009;5939.584229103959;5954.940450595498;5979.880402391949;5881.264841894763;5898.734556944937;5971.581989260753;6018.638881667092;6042.57701960863;4849.539293767104;5071.391433028023;5203.635745497817;5207.687538747668;5290.941811637668;5252.028374750684;5331.786728530874;5337.359516857982;5342.278792363039;5342.439043900393;5104.974475127638;5187.740612969336;5194.844154675366;5225.477452254782;5259.737013149327;5233.502817232356;5246.7901283948595;5255.658382205144;5291.391489978663;5315.840524784263;5223.582113430916;5240.633155679104;5254.52709256165;5259.184826351921;5271.683698978073;5780.1907732917425;5858.589898707092;5879.32387775407;5942.851428714273;5958.702064896738;6463;6482.384173503509;6484.6433446160445;6486.318936511692;6496.252930912911;6496.187976502937;6521.282658907517;6522.673866306665;6530.575512591714;6536.379043990813;5768.221290125864;5916.940830591725;6029.246344206974;6034.336223015464;6037.939620603782;6456.322086180883;6543.411093001558;6554.213494380705;6588.703508342136;6593.86812263752;6095.529019716752;6176.166217560662;6242.843928901723;6292.874142517124;6361.5546911716665;6439.130717353191;6439.8068057958335;6464.25661048976;6487.805365839033;6504.642244676588;6179.104029915637;6273.153124328249;6461.030845373231;6464.709088091047;6472.741090356333;6308.148399965946;6384.904226436607;6387.776427825004;6421.29365769765;6435.292117867031;4846.709197448165;4849.248366503198;4858.514148585114;4861.465238823726;4893.6085113191575;4312.460942382202;4318.503372112247;4338.023944612437;4351.804168812411;4359.67302452311;5897.144913987448;5992.221011268594;6037.969810150921;6104.38956104386;6115.602485838406;6471.385218404338;6486.545941784124;6488.383603557654;6488.448481878999;6501.674916254168;5992.131140984533;6082.665453400105;6095.024796032613;6100.328963813977;6108.35862234459;5434.782608695607;5516.3380394352935;5550.47270509301;5628.971855140698;5652.576056795757;6809.182898052265;6848.7260509579055;6849.828754281183;6851.8972215415815;6853.554518956252;6529.67351632407;6531;6537.1501704778075;6546;6548.639824809681;6419.069234960998;6561.40947314744;6655.035019921987;6741.2921643226755;6807.591544507345;6327.557071004985;6408.134901788386;6414.775482858077;6416.582922110048;6421.64680942534;6552.3775241352;6552.967235163985;6555.2133743949125;6561.212654481302;6563.868722625616;6100.005955098508;6108.664023478751;6159.23009623797;6190.164327815687;6311.90532142009;6283.59156654816;6312.810615681537;6336.619802811846;6345.619262844244;6353.205849268841;6217.564770466024;6269.435750782452;6277.058441233851;6278.152449419453;6323.652199129092;6119.2350956130485;6154.599951003171;6293.779717709858;6352.872942541031;6358.364163583789;6072.271327440735;6126.632402055891;6157.32269450369;6159.476444502088;6168.444839964424 16:31:22 INFO - PID 12930 | |1;ext.html;721.577876941989;795.4988357334879;814.4665244265007;1130.2653275371013;1152.1877076660955;28633.713662863363;36329.636703632954;37036;37151.44272835906;37182.8140859296;39169;39668.20663586727;39702.40446393307;39764.403533947014;39772.204555908866;5732.5987180897355;5839.240898683186;7325.597092159943;7455.925440745577;7456.328930396263;7187.7843664690145;7316.085489313836;7319.41444684424;7337.2662733726465;7342.375898048672;7451.254874512532;7484.101907771075;7492.887606685904;7497.100347958225;7503.324700776929;7363.631818409085;7428.257174282609;7434.962825185894;7442.66508007141;7449.068866391701;7290.0158478605235;7370;7370.336669699753;7372.926270737277;7375.4099672026105;34841.82579087114;35054.298914021834;35065.123371915906;35125.82437087824;35130.648693512994;3434.3989801784587;3451.9827400862837;3466.722662187019;3468.6704763047724;3470.0804286864;28214.15357539277;28329.57505637417;28420.85789571039;28421.147365579065;28467.57298640522;3247.1070455624654;3254.8372581370836;3255.951160732591;3269.5749552558254;3283.6387997320276;185931;186099;186243.06878465656;186606;186687;47075;47133.29300060433;47165.52834471611;47184;47225.763871180425;48292;48430;48518.5148148514;48630;48642.513574864504;13746.862531374558;13810.93094534521;13812.585622431343;13838.515651952133;13855.86144138566;9454.716358509118;9634.566444510014;11017.283876548;11080.778384432266;11095.334279943068;12449.875501245053;12457.937710311571;12516.436760345807;12542.557605875441;12734.172278801849;5290;5386.380566234933;5401.162819762943;5426;5461.1262198048125;1054.3094273250879;1088.5700148441322;1091.8635170603675;1091.9235653504338;1095.5562996986234;23838.761612384;24507.87746061294;24525.63211551828;24812.751872481043;29164.854175728986;1124.5389390349917;1132.326265871805;1144.2333636463422;1153.5789436855655;1161.4192903548226;12628.936855315664;13029.543965961146;13073.738525229443;13314;13357.332133393485;234252;235680;235766;236588;237138;49271;49334.506654933706;49396;49499.25751113736;49500.50499494959;1448.1166488442043;1453.4694836384856;1470.0885451020222;1495.9925200374146;1498.8351281359041;23213.303600892;24122.517549648557;24125.396865078514;24229.757702423456;24261.27216183517;37021.44467832931;37027.44458833174;37050.814745926094;37093;37131;202851;204276;204330;205015;205066;31272.374552509278;31337.8433107833;33805.66194338025;34610.65389346175;34620.653793461744;41224.587754122076;43024;43038;43138.13723725445;43262.78368608137;31595.36809263756;31759.364812703156;31836.363272734878;31935;32594.674053259165;31647.20881977969;32033;32122;32452;32500.83749581237;37853.621463785006;38126.428103579026;38216;38221.2355752889;38298.425523616614;30075.69924300729;30442.543361850032;30877.38245235038;30988;31602.839716028215;24972.37569060788;25326.87336563379;25421.364465888502;25622;25844.870775646003 16:31:22 INFO - PID 12930 | |2;jquery.html;383.47081028181105;441.38647280280395;447.50550641541093;459.0795455112499;468.2998916619654;19356.806431935693;19830.20679172833;19929.701054484194;19976.900115499422;20364.898175509137;19100.713489297672;19728.506787330323;19963.800361996375;20234.392968210977;21684.2410515632;5585.357683866367;5750.166225897243;5750.942490575083;5752.137179423078;5757.971210143944;5295.364556253255;5497.340319161706;5524.171374293868;5531.502164805167;5535.25274087999;5497.615166938316;5500.50495455409;5505.284313039299;5507.807226747064;5508.476694714016;5535.833924982237;5702.657840529581;5743.770249190027;5756.539476841864;5761.366249712528;5169.84936662106;5205.947940520584;5242.13504771716;5248.422673505911;5248.737563121828;25421.491570168677;26107.608385874228;26172;26408.339791505176;26440;2098;2173.217641649005;2178.063432723928;2179.1174574297434;2185.9562808743894;17125.828741712674;17281.30874765008;17478.912605437017;17535.210915508833;17762.911185444118;2197.450637340665;2198.1756841184556;2215.4018415028013;2233.553289342138;2256.9322920312416;106066;107587.46206268997;108546.45726771395;108868.99999999921;115169.84830151674;11293.830592541119;11737.35444550558;12120.45457954394;12251.38743062861;12342.506299747909;17434.912825436044;17900.820991789915;17907.641847163246;18039.72940405895;18048.729269060976;8534.146585341416;8566.828663426697;8786.912130878738;8934.06192349807;8963.731088067369;7968.960155199303;7991.041075071029;8007;8149.877751833727;8168.632411541495;7962.56205908669;7994.560299183485;8065.516069035877;8087;8089.191080891981;2174.4346469917937;2206.1837120265604;2207.459172502747;2309.2148669452467;2340.414896275931;1027.2141811514198;1047.162270183865;1052.7052425320921;1052.889446608095;1056.3609016545033;18653.33666583354;20188.697169542174;20297.188112475334;20626.174953002013;20859.1656333748;1047.2983101322131;1053.2364036073784;1056.0812093478723;1058.629479682105;1066.8826429092794;11091.889081109248;11111.777764444665;11120.443977801078;11122.721931951604;11289.943550282196;453039;460771;462686;464084;465996;91893;92190;92588.53705731429;93594.53202733943;93805.53097234607;1165.6328256599145;1197.0902114393125;1216.6106845809434;1221.9694507637203;1228.5208768580083;16726.247318870435;17254.96470211791;17292.913535432242;17576.82423175752;17969.910150449163;43092.337151342486;43645;43772;44049.779751101036;44057.77971110124;55273;55693;56129;56974.715126424104;57503.712481437324;8755.387122901337;8799.47203167812;8957.552122393985;8978.236849867835;9214;12931.03017273727;13098.279594622387;13345.332733363488;13364.532241371506;13828.377723002288;10987.450627468435;11023.83464248053;11041.834372484258;11473.483693233658;11513.42432878336;10739;10931.125509959025;11017.56408854608;11020.283681560673;11132.55469781232;12503.124781265224;12572.937135314265;13021.156278677472;13060.659062483008;13382.93308533451;8940.374173807772;8976.730698079067;9161.541922903962;9419.199368053793;9521.714348569554;11358.375289359165;11526.884731152917;11596.768064638829;11615.5353785851;11760.941195293968 16:31:22 INFO - PID 12930 | |3;mootools.html;1345;1376.181172202539;1396.6159306190802;1402.845686974432;1412.8799052080572;10953.945230273848;11479.139064570149;11480.598179063734;11488.310701357925;11500.252483588562;11063.944680276607;11296.378699171542;11313.264637798537;11384.829227561593;11403.828942565848;2260.0846657103857;2298.839081264312;2300.25241796416;2301.827362947777;2307.9844868257996;1910.4746194796473;1991.2930909527126;2218.4010317214425;2260.062074239189;2278.6354183330673;2196.231319038332;2273.6703178039174;2277.6811246425514;2278.943026424336;2283.5318759654315;2064.8554601177925;2069.782672819355;2085.8435617328687;2090.1848279171136;2264.8867556622267;1910.9713354299697;1975.7157847399162;2155.2564365293947;2253.143805353955;2263.8868056597103;11211.607593734261;11297.435128243555;11308.208425410225;11387.886121138765;11426.600068997544;6267.153934218868;6527.869442611169;6530.771423000172;6540.803775886683;6548.476121910236;3765.811709414546;3925.2738243424774;3964.7422917510557;3987.920241595181;3999.14018486024;1237.789575772121;1250.2810883741886;1277.47623474376;1325.4631874091008;1344.7646661834237;17642;17807.643847123116;17832.197551110232;17840.107994600217;17850.46448606531;5227.634065615446;5265.710385928811;5464;5464.453554644504;5497.862553436196;12723.809142862865;12736.108472407028;12744.80882786759;12746.681332966564;12768.170068945488;6316.178896743486;6402.967985160138;6407.935920640827;6408;6413.903791443132;5953.880922381615;5969.552283578749;6090.390960903874;6100.755969761249;6241.625502469867;4136.772477513766;4272.530021697611;4303.354496825501;4318.207943703328;4320.351947207944;1194.235689158939;1222.1872454817624;1229.9938500307442;1232.9260244385366;1239.8822111899356;848.325581162977;855.6320782063773;876.5047748022347;883.9216156289317;905.1536813079791;6396.42432181106;6405.615663060231;6448.580842245333;6483.059956306403;6504.642244676494;635.2408871398727;644.5005121031145;647.0746832030148;654.8559316950264;661.2957200581366;4111.814968326433;4177.519585247735;4184.895377615584;4200.411942328076;4233.597808208216;15039;15722.606934826492;15754.606134846721;15779.842201577838;15831.208439577975;14945.925270373578;15572.688546229012;15809.525714228592;15903;15973.520794376187;9479.526023698925;9506.33455658111;9518.809623807623;9618.663346782829;9619.470929098827;6845.931540684529;6912.239653638197;7064.364207221375;7071.7878463646175;7073.6816843243205;13692;13988.300584970913;14011.929940350234;14105.506307279196;14120.78818817698;14171.291435427982;14244.715105698035;14301.499447519287;14482.637934051732;14664.633384165481;8778.4294020891;8793.29653627721;8844.955775221082;8916.33127515426;8931.821363572562;10882.727931801768;11022.063124634496;11087.889121108685;11101.056410205223;11108.88891111111;9397.530123493661;9529.809403811747;9561.713148605553;9621.759456013375;9725.076117769084;9187.816243674955;9406.200472959876;9427.434353938786;9436.575354109218;9509.572069256761;8331.83336333282;8570.314374850122;8589.312854971713;8594.742157735278;8605.827883442422;6308.211473565804;6311.96844015777;6334.714937827716;6410.4871610272;6600.1089852869245;6014.1580178775885;6096.542759292982;6177.81466556004;6302.338254483213;6311.305756366888 16:31:22 INFO - PID 12930 | |4;prototype.html;414.6537641069707;416.52932186629107;427.16702430261;432.401124442647;433.32088158386273;5677.01956472852;5912.733926973286;5943.970280148598;5951.404859514047;5966.671833049191;5770.855728606787;5801.013827649299;5821.301443826736;5826.096954971976;5839.386864379243;4532.614727748137;4556.476005259405;4562.817487300504;4569.885752856172;4572.359869618256;4467.262901621245;4530.116627257686;4558.430196225472;4571.679982401233;4600.217962946307;4522.728636281834;4527.954720452803;4528.343390208434;4530.773461326937;4532.8866778330475;4519.5932366087045;4537.818487260505;4546.749074411779;4550.453945526525;4552.704074235164;4044.5955404459614;4061.346349198507;4123.958760412417;4137.462129923121;4145.357469592218;5620.690862002588;5635.464630860061;5669;5690.886182276331;5712.485876271156;5401.945980540184;5414.3232095988005;5420.647657902224;5422.376426710939;5437.374701909292;6087.178230938811;6113.363344691249;6145.907811382833;6222.257767825016;6235.158253635746;4642.442906851199;4647.1402790483535;4654.138984287883;4671.85984420468;4702;6113;6278;6438.613683178977;6490.288546079467;6504.682611867934;4356.76037817923;4730.645201609893;4841.854744357604;4872.3909511311085;4908.846421091041;4927.8665906841625;4952.752362381939;4974.701517908937;4976.751162441863;4996.401871027046;5346.625736198506;5435.374931882884;5466.098093814476;5512.944870551243;5517.503424691797;6215.595986260879;6225.159603453566;6229.283632382334;6244.937550624436;6277.372262773686;1708.63264398155;1713.9742903856452;1721.8708596855286;1724.1206984437777;1724.9564013771737;1137.192593258779;1203.5967950736426;1213.2477863724548;1223.7246619510538;1231.950721971129;5955.285365756137;5962.25471816023;6001.879962400815;6012.969935150384;6017.11895827189;6353.36466353361;6356.650384228912;6358.427741503287;6359.554831161774;6379.5215358847345;5824.737886795105;6031;6074.331823499411;6075.936711075544;6077.787277445356;3372.898813035613;3373.224158443572;3374.3420033093803;3392.3208012033706;3397.1507123219194;6518.641474718936;6700.83247918806;6748.156480439945;6787.6266805325195;6795.93204067953;6616.636085015274;6623.4038936495945;6642.302558231316;6657.3342665733035;6668.699908504129;6326.810195694136;6416.454601358843;6491;6653.600783952881;6687.699053542505;6372.044193371032;6372.139761132188;6372.5220608455475;6380.7447702091395;6390.64851433157;6141.877162456815;6294.1188233648245;6310.075426994627;6352.809415717536;6398.616083035033;6714.3957043866285;6725.932740672726;6728.461723062242;6740.629265390255;6748.7300507979135;4891.290762839297;5007.574356179765;5010.774515146754;5031.169856973558;5041.6470847040355;6435.935640643534;6460.612363258219;6462.806115816533;6468.38550337736;6468.417842394207;6008.399160083956;6235.719392627433;6260.436560709558;6263.655498947602;6276.717547710273;6208.192934918432;6252.874942501216;6262.248530176408;6262.34245404226;6274.466670333073;6202.937970620236;6222.542095872196;6226.719797609027;6233.376662333731;6256.717372938446;6079;6245.687715614292;6254.687265636791;6269.247690277196;6269.404406581368;6086.756529738761;6086.817395478142;6105.16110008848;6113.29697084841;6118.694065296806 16:31:22 INFO - PID 12930 | |5;yui.html;860.2601762484264;870.7648934787611;880.4057261348588;881.8544940084886;895.5343221524805;15952.601184970366;16168.83831161688;16380.017198968055;16390.01659900407;16434.096124713153;15516.30176642051;15706.607334816606;15727.66314863238;15832.208389580532;15871.285792139353;986.5264672956966;1002.293383164867;1003.8142943555429;1004.4877112672553;1004.6333088422746;927.8190752803206;998.3760149906309;1007.0584003956287;1007.6422869881196;1007.8135544924213;991.3159919655451;1016.232744278069;1025.4565080507343;1028.2545154762774;1053.420618659738;1023.6468418395641;1023.923205759571;1023.9436830974294;1025.0364657222187;1025.1235193909213;760.1676164599754;761.8095476130967;761.8285885675679;762.142589586715;762.2034973452755;15923.44267950616;16055.598610034725;16089.356425742957;16755.329786808514;17064.488065357935;17486.737698934525;17643.647127057517;17750.556236094202;17786.110694465224;18149.637007259913;12988.610341689764;13137.934310328483;13217.73564528704;14654.56036318912;14680.119192848357;3400.6429324920773;3420.2817408344276;3429.8285085745615;3434.295969326282;3434.4161492546327;82094.58952705258;82892.58553707253;92344;92637;93331;14556.49052283165;14705.264736763333;14831;14902.1803800792;14918.4778532753;26661.333466663487;26859.32851678724;27160.320991974964;27355.72644273532;27383.86308068447;7950.284474397332;8082.9191708083345;8141.51150930946;8160.795980100545;8164.265216130577;3777.4522694209736;3822.2546603412625;3832.7317087803585;3833.5783063862955;3856.382978723391;4479.10417916418;4549.454065512094;4561.24739417999;4562.452505699337;4569.360289559464;839.4249938791947;860.6428332242175;867.8741582470507;871.2942516561606;879.7360791762574;708.1113202930289;721.1670520548774;726.963651817407;736.4255880413286;762.2111114996063;11427.657170284905;12172.513099475922;12909.54816581415;13310.334483275798;13685.521006764715;703.3669697272394;711.3953139831102;723.3345322303388;730.3791776989516;743.4758495260828;5223.347081614798;5410.065632428487;5661;5695.060315047971;5706.28671416073;78474.05178145657;79948.60025699834;80719.59640201762;80828.59585702034;82608.58695706484;65961.67019164874;66156;66211;66315;66835.33164668389;60052;60588;60955.69522152361;60994;61428;11093.454861631675;11144.498497567467;11773.882261177621;11833.70415739579;11941.52233910634;31839;32002;32718.83640581782;32964.67035329712;34091;38827;39482.012949676486;39648.603513965645;40470.392944106454;40948.79525602353;14194.148351098554;14284.142951422948;14394.064385814889;14412.711745764816;14568.271586420424;15978.840211597735;16227.594310142342;16278.755818662492;16318.755218671968;16570.254338554554;15555.611109722347;15608;15626.921865390601;15701.607459813597;15749.897507174388;14872.553823385315;15306.08163510193;15341.539753807403;15532.844671553592;15689.450869219523;15141;15270.847291526943;15310.846891531388;15311.923440382727;15604.453844115402;10959.780804384027;11140.554377825121;11450.94274528622;11466.082713382742;11570.19008669385;12475.625731228078;12885;13018.609441716764;13029.869701302865;13085.607431777062 16:31:22 INFO - PID 12930 | __end_tp_report 16:31:22 INFO - PID 12930 | __start_cc_report 16:31:22 INFO - PID 12930 | _x_x_mozilla_cycle_collect,1627 16:31:22 INFO - PID 12930 | __end_cc_report 16:31:22 INFO - PID 12930 | __startTimestamp1494372682563__endTimestamp 16:31:22 INFO - PID 12930 | 16:31:22 INFO - PID 12930 | ------- Summary: start ------- 16:31:22 INFO - PID 12930 | Number of tests: 6 16:31:22 INFO - PID 12930 | 16:31:22 INFO - PID 12930 | [#0] dojo.html Cycles:165 Average:5932.87 Median:6157.32 stddev:734.02 (11.9%) stddev-sans-first:708.68 16:31:22 INFO - PID 12930 | Values: 3391.7 3409.2 3424.3 3425.4 3460.0 5762.2 5883.3 5939.6 5954.9 5979.9 5881.3 5898.7 5971.6 6018.6 6042.6 4849.5 5071.4 5203.6 5207.7 5290.9 5252.0 5331.8 5337.4 5342.3 5342.4 5105.0 5187.7 5194.8 5225.5 5259.7 5233.5 5246.8 5255.7 5291.4 5315.8 5223.6 5240.6 5254.5 5259.2 5271.7 5780.2 5858.6 5879.3 5942.9 5958.7 6463.0 6482.4 6484.6 6486.3 6496.3 6496.2 6521.3 6522.7 6530.6 6536.4 5768.2 5916.9 6029.2 6034.3 6037.9 6456.3 6543.4 6554.2 6588.7 6593.9 6095.5 6176.2 6242.8 6292.9 6361.6 6439.1 6439.8 6464.3 6487.8 6504.6 6179.1 6273.2 6461.0 6464.7 6472.7 6308.1 6384.9 6387.8 6421.3 6435.3 4846.7 4849.2 4858.5 4861.5 4893.6 4312.5 4318.5 4338.0 4351.8 4359.7 5897.1 5992.2 6038.0 6104.4 6115.6 6471.4 6486.5 6488.4 6488.4 6501.7 5992.1 6082.7 6095.0 6100.3 6108.4 5434.8 5516.3 5550.5 5629.0 5652.6 6809.2 6848.7 6849.8 6851.9 6853.6 6529.7 6531.0 6537.2 6546.0 6548.6 6419.1 6561.4 6655.0 6741.3 6807.6 6327.6 6408.1 6414.8 6416.6 6421.6 6552.4 6553.0 6555.2 6561.2 6563.9 6100.0 6108.7 6159.2 6190.2 6311.9 6283.6 6312.8 6336.6 6345.6 6353.2 6217.6 6269.4 6277.1 6278.2 6323.7 6119.2 6154.6 6293.8 6352.9 6358.4 6072.3 6126.6 6157.3 6159.5 6168.4 16:31:22 INFO - PID 12930 | 16:31:22 INFO - PID 12930 | [#1] ext.html Cycles:180 Average:37043.94 Median:17932.12 stddev:54452.71 (303.7%) stddev-sans-first:54537.16 16:31:22 INFO - PID 12930 | Values: 721.6 795.5 814.5 1130.3 1152.2 28633.7 36329.6 37036.0 37151.4 37182.8 39169.0 39668.2 39702.4 39764.4 39772.2 5732.6 5839.2 7325.6 7455.9 7456.3 7187.8 7316.1 7319.4 7337.3 7342.4 7451.3 7484.1 7492.9 7497.1 7503.3 7363.6 7428.3 7435.0 7442.7 7449.1 7290.0 7370.0 7370.3 7372.9 7375.4 34841.8 35054.3 35065.1 35125.8 35130.6 3434.4 3452.0 3466.7 3468.7 3470.1 28214.2 28329.6 28420.9 28421.1 28467.6 3247.1 3254.8 3256.0 3269.6 3283.6 185931.0 186099.0 186243.1 186606.0 186687.0 47075.0 47133.3 47165.5 47184.0 47225.8 48292.0 48430.0 48518.5 48630.0 48642.5 13746.9 13810.9 13812.6 13838.5 13855.9 9454.7 9634.6 11017.3 11080.8 11095.3 12449.9 12457.9 12516.4 12542.6 12734.2 5290.0 5386.4 5401.2 5426.0 5461.1 1054.3 1088.6 1091.9 1091.9 1095.6 23838.8 24507.9 24525.6 24812.8 29164.9 1124.5 1132.3 1144.2 1153.6 1161.4 12628.9 13029.5 13073.7 13314.0 13357.3 234252.0 235680.0 235766.0 236588.0 237138.0 49271.0 49334.5 49396.0 49499.3 49500.5 1448.1 1453.5 1470.1 1496.0 1498.8 23213.3 24122.5 24125.4 24229.8 24261.3 37021.4 37027.4 37050.8 37093.0 37131.0 202851.0 204276.0 204330.0 205015.0 205066.0 31272.4 31337.8 33805.7 34610.7 34620.7 41224.6 43024.0 43038.0 43138.1 43262.8 31595.4 31759.4 31836.4 31935.0 32594.7 31647.2 32033.0 32122.0 32452.0 32500.8 37853.6 38126.4 38216.0 38221.2 38298.4 30075.7 30442.5 30877.4 30988.0 31602.8 24972.4 25326.9 25421.4 25622.0 25844.9 16:31:22 INFO - PID 12930 | 16:31:22 INFO - PID 12930 | [#2] jquery.html Cycles:180 Average:29622.77 Median:2191.70 stddev:76813.90 (3504.8%) stddev-sans-first:76998.01 16:31:22 INFO - PID 12930 | Values: 383.5 441.4 447.5 459.1 468.3 19356.8 19830.2 19929.7 19976.9 20364.9 19100.7 19728.5 19963.8 20234.4 21684.2 5585.4 5750.2 5750.9 5752.1 5758.0 5295.4 5497.3 5524.2 5531.5 5535.3 5497.6 5500.5 5505.3 5507.8 5508.5 5535.8 5702.7 5743.8 5756.5 5761.4 5169.8 5205.9 5242.1 5248.4 5248.7 25421.5 26107.6 26172.0 26408.3 26440.0 2098.0 2173.2 2178.1 2179.1 2186.0 17125.8 17281.3 17478.9 17535.2 17762.9 2197.5 2198.2 2215.4 2233.6 2256.9 106066.0 107587.5 108546.5 108869.0 115169.8 11293.8 11737.4 12120.5 12251.4 12342.5 17434.9 17900.8 17907.6 18039.7 18048.7 8534.1 8566.8 8786.9 8934.1 8963.7 7969.0 7991.0 8007.0 8149.9 8168.6 7962.6 7994.6 8065.5 8087.0 8089.2 2174.4 2206.2 2207.5 2309.2 2340.4 1027.2 1047.2 1052.7 1052.9 1056.4 18653.3 20188.7 20297.2 20626.2 20859.2 1047.3 1053.2 1056.1 1058.6 1066.9 11091.9 11111.8 11120.4 11122.7 11289.9 453039.0 460771.0 462686.0 464084.0 465996.0 91893.0 92190.0 92588.5 93594.5 93805.5 1165.6 1197.1 1216.6 1222.0 1228.5 16726.2 17255.0 17292.9 17576.8 17969.9 43092.3 43645.0 43772.0 44049.8 44057.8 55273.0 55693.0 56129.0 56974.7 57503.7 8755.4 8799.5 8957.6 8978.2 9214.0 12931.0 13098.3 13345.3 13364.5 13828.4 10987.5 11023.8 11041.8 11473.5 11513.4 10739.0 10931.1 11017.6 11020.3 11132.6 12503.1 12572.9 13021.2 13060.7 13382.9 8940.4 8976.7 9161.5 9419.2 9521.7 11358.4 11526.9 11596.8 11615.5 11760.9 16:31:22 INFO - PID 12930 | 16:31:22 INFO - PID 12930 | [#3] mootools.html Cycles:180 Average:7226.66 Median:3845.54 stddev:4784.15 (124.4%) stddev-sans-first:4777.16 16:31:22 INFO - PID 12930 | Values: 1345.0 1376.2 1396.6 1402.8 1412.9 10953.9 11479.1 11480.6 11488.3 11500.3 11063.9 11296.4 11313.3 11384.8 11403.8 2260.1 2298.8 2300.3 2301.8 2308.0 1910.5 1991.3 2218.4 2260.1 2278.6 2196.2 2273.7 2277.7 2278.9 2283.5 2064.9 2069.8 2085.8 2090.2 2264.9 1911.0 1975.7 2155.3 2253.1 2263.9 11211.6 11297.4 11308.2 11387.9 11426.6 6267.2 6527.9 6530.8 6540.8 6548.5 3765.8 3925.3 3964.7 3987.9 3999.1 1237.8 1250.3 1277.5 1325.5 1344.8 17642.0 17807.6 17832.2 17840.1 17850.5 5227.6 5265.7 5464.0 5464.5 5497.9 12723.8 12736.1 12744.8 12746.7 12768.2 6316.2 6403.0 6407.9 6408.0 6413.9 5953.9 5969.6 6090.4 6100.8 6241.6 4136.8 4272.5 4303.4 4318.2 4320.4 1194.2 1222.2 1230.0 1232.9 1239.9 848.3 855.6 876.5 883.9 905.2 6396.4 6405.6 6448.6 6483.1 6504.6 635.2 644.5 647.1 654.9 661.3 4111.8 4177.5 4184.9 4200.4 4233.6 15039.0 15722.6 15754.6 15779.8 15831.2 14945.9 15572.7 15809.5 15903.0 15973.5 9479.5 9506.3 9518.8 9618.7 9619.5 6845.9 6912.2 7064.4 7071.8 7073.7 13692.0 13988.3 14011.9 14105.5 14120.8 14171.3 14244.7 14301.5 14482.6 14664.6 8778.4 8793.3 8845.0 8916.3 8931.8 10882.7 11022.1 11087.9 11101.1 11108.9 9397.5 9529.8 9561.7 9621.8 9725.1 9187.8 9406.2 9427.4 9436.6 9509.6 8331.8 8570.3 8589.3 8594.7 8605.8 6308.2 6312.0 6334.7 6410.5 6600.1 6014.2 6096.5 6177.8 6302.3 6311.3 16:31:22 INFO - PID 12930 | 16:31:22 INFO - PID 12930 | [#4] prototype.html Cycles:180 Average:5291.60 Median:5958.77 stddev:1517.40 (25.5%) stddev-sans-first:1476.85 16:31:22 INFO - PID 12930 | Values: 414.7 416.5 427.2 432.4 433.3 5677.0 5912.7 5944.0 5951.4 5966.7 5770.9 5801.0 5821.3 5826.1 5839.4 4532.6 4556.5 4562.8 4569.9 4572.4 4467.3 4530.1 4558.4 4571.7 4600.2 4522.7 4528.0 4528.3 4530.8 4532.9 4519.6 4537.8 4546.7 4550.5 4552.7 4044.6 4061.3 4124.0 4137.5 4145.4 5620.7 5635.5 5669.0 5690.9 5712.5 5401.9 5414.3 5420.6 5422.4 5437.4 6087.2 6113.4 6145.9 6222.3 6235.2 4642.4 4647.1 4654.1 4671.9 4702.0 6113.0 6278.0 6438.6 6490.3 6504.7 4356.8 4730.6 4841.9 4872.4 4908.8 4927.9 4952.8 4974.7 4976.8 4996.4 5346.6 5435.4 5466.1 5512.9 5517.5 6215.6 6225.2 6229.3 6244.9 6277.4 1708.6 1714.0 1721.9 1724.1 1725.0 1137.2 1203.6 1213.2 1223.7 1232.0 5955.3 5962.3 6001.9 6013.0 6017.1 6353.4 6356.7 6358.4 6359.6 6379.5 5824.7 6031.0 6074.3 6075.9 6077.8 3372.9 3373.2 3374.3 3392.3 3397.2 6518.6 6700.8 6748.2 6787.6 6795.9 6616.6 6623.4 6642.3 6657.3 6668.7 6326.8 6416.5 6491.0 6653.6 6687.7 6372.0 6372.1 6372.5 6380.7 6390.6 6141.9 6294.1 6310.1 6352.8 6398.6 6714.4 6725.9 6728.5 6740.6 6748.7 4891.3 5007.6 5010.8 5031.2 5041.6 6435.9 6460.6 6462.8 6468.4 6468.4 6008.4 6235.7 6260.4 6263.7 6276.7 6208.2 6252.9 6262.2 6262.3 6274.5 6202.9 6222.5 6226.7 6233.4 6256.7 6079.0 6245.7 6254.7 6269.2 6269.4 6086.8 6086.8 6105.2 6113.3 6118.7 16:31:22 INFO - PID 12930 | 16:31:22 INFO - PID 12930 | [#5] yui.html Cycles:180 Average:18041.22 Median:22405.49 stddev:22155.51 (98.9%) stddev-sans-first:22180.09 16:31:22 INFO - PID 12930 | Values: 860.3 870.8 880.4 881.9 895.5 15952.6 16168.8 16380.0 16390.0 16434.1 15516.3 15706.6 15727.7 15832.2 15871.3 986.5 1002.3 1003.8 1004.5 1004.6 927.8 998.4 1007.1 1007.6 1007.8 991.3 1016.2 1025.5 1028.3 1053.4 1023.6 1023.9 1023.9 1025.0 1025.1 760.2 761.8 761.8 762.1 762.2 15923.4 16055.6 16089.4 16755.3 17064.5 17486.7 17643.6 17750.6 17786.1 18149.6 12988.6 13137.9 13217.7 14654.6 14680.1 3400.6 3420.3 3429.8 3434.3 3434.4 82094.6 82892.6 92344.0 92637.0 93331.0 14556.5 14705.3 14831.0 14902.2 14918.5 26661.3 26859.3 27160.3 27355.7 27383.9 7950.3 8082.9 8141.5 8160.8 8164.3 3777.5 3822.3 3832.7 3833.6 3856.4 4479.1 4549.5 4561.2 4562.5 4569.4 839.4 860.6 867.9 871.3 879.7 708.1 721.2 727.0 736.4 762.2 11427.7 12172.5 12909.5 13310.3 13685.5 703.4 711.4 723.3 730.4 743.5 5223.3 5410.1 5661.0 5695.1 5706.3 78474.1 79948.6 80719.6 80828.6 82608.6 65961.7 66156.0 66211.0 66315.0 66835.3 60052.0 60588.0 60955.7 60994.0 61428.0 11093.5 11144.5 11773.9 11833.7 11941.5 31839.0 32002.0 32718.8 32964.7 34091.0 38827.0 39482.0 39648.6 40470.4 40948.8 14194.1 14284.1 14394.1 14412.7 14568.3 15978.8 16227.6 16278.8 16318.8 16570.3 15555.6 15608.0 15626.9 15701.6 15749.9 14872.6 15306.1 15341.5 15532.8 15689.5 15141.0 15270.8 15310.8 15311.9 15604.5 10959.8 11140.6 11450.9 11466.1 11570.2 12475.6 12885.0 13018.6 13029.9 13085.6 16:31:22 INFO - PID 12930 | -------- Summary: end -------- 16:31:22 INFO - PID 12930 | 16:31:23 INFO - TEST-INFO | 12930: exit 0 16:31:23 INFO - TEST-OK | dromaeo_css | took 1115239ms 16:31:23 INFO - TEST-START | kraken 16:31:23 INFO - operating with platform_type : linux_ 16:31:23 INFO - Initialising browser for kraken test... 16:31:23 INFO - TEST-INFO | started process 13270 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpp9UdGs/profile http://localhost:52687/getInfo.html) 16:31:27 INFO - PID 13270 | __metrics Screen width/height:1600/1200 16:31:27 INFO - PID 13270 | colorDepth:24 16:31:27 INFO - PID 13270 | Browser inner width/height: 1024/695 16:31:27 INFO - PID 13270 | __metrics 16:31:28 INFO - PID 13270 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:31:28 INFO - PID 13270 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:31:29 INFO - TEST-INFO | 13270: exit 0 16:31:29 INFO - Browser initialized. 16:31:29 INFO - Running cycle 1/1 for kraken test... 16:31:29 INFO - TEST-INFO | started process 13364 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpp9UdGs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:31:40 INFO - PID 13364 | RSS: Main: 145432576 16:31:40 INFO - PID 13364 | 16:31:43 INFO - PID 13364 | Cycle 1(1): loaded http://localhost:52687/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52687/tests/kraken/driver.html?testName=audio-beat-detection) 16:31:44 INFO - PID 13364 | RSS: Main: 217444352 16:31:44 INFO - PID 13364 | 16:31:46 INFO - PID 13364 | Cycle 1(1): loaded http://localhost:52687/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52687/tests/kraken/driver.html?testName=audio-dft) 16:31:47 INFO - PID 13364 | RSS: Main: 178434048 16:31:47 INFO - PID 13364 | 16:31:50 INFO - PID 13364 | Cycle 1(1): loaded http://localhost:52687/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52687/tests/kraken/driver.html?testName=audio-fft) 16:31:50 INFO - PID 13364 | RSS: Main: 259690496 16:31:50 INFO - PID 13364 | 16:31:52 INFO - PID 13364 | Cycle 1(1): loaded http://localhost:52687/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52687/tests/kraken/driver.html?testName=audio-oscillator) 16:31:53 INFO - PID 13364 | RSS: Main: 171499520 16:31:53 INFO - PID 13364 | 16:31:55 INFO - PID 13364 | Cycle 1(1): loaded http://localhost:52687/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52687/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:31:56 INFO - PID 13364 | RSS: Main: 173637632 16:31:56 INFO - PID 13364 | 16:31:59 INFO - PID 13364 | Cycle 1(1): loaded http://localhost:52687/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52687/tests/kraken/driver.html?testName=imaging-darkroom) 16:31:59 INFO - PID 13364 | RSS: Main: 234835968 16:31:59 INFO - PID 13364 | 16:32:03 INFO - PID 13364 | Cycle 1(1): loaded http://localhost:52687/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52687/tests/kraken/driver.html?testName=imaging-desaturate) 16:32:03 INFO - PID 13364 | RSS: Main: 252780544 16:32:03 INFO - PID 13364 | 16:32:06 INFO - PID 13364 | Cycle 1(1): loaded http://localhost:52687/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52687/tests/kraken/driver.html?testName=json-parse-financial) 16:32:07 INFO - PID 13364 | RSS: Main: 264085504 16:32:07 INFO - PID 13364 | 16:32:08 INFO - PID 13364 | Cycle 1(1): loaded http://localhost:52687/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52687/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:32:09 INFO - PID 13364 | RSS: Main: 198483968 16:32:09 INFO - PID 13364 | 16:32:10 INFO - PID 13364 | Cycle 1(1): loaded http://localhost:52687/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52687/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:32:11 INFO - PID 13364 | RSS: Main: 204263424 16:32:11 INFO - PID 13364 | 16:32:13 INFO - PID 13364 | Cycle 1(1): loaded http://localhost:52687/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52687/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:32:13 INFO - PID 13364 | RSS: Main: 213303296 16:32:13 INFO - PID 13364 | 16:32:16 INFO - PID 13364 | Cycle 1(1): loaded http://localhost:52687/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52687/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:32:17 INFO - PID 13364 | RSS: Main: 222040064 16:32:17 INFO - PID 13364 | 16:32:20 INFO - PID 13364 | Cycle 1(1): loaded http://localhost:52687/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52687/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:32:20 INFO - PID 13364 | RSS: Main: 218050560 16:32:20 INFO - PID 13364 | 16:32:22 INFO - PID 13364 | Cycle 1(1): loaded http://localhost:52687/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52687/tests/kraken/driver.html?testName=ai-astar) 16:32:22 INFO - PID 13364 | RSS: Main: 266883072 16:32:22 INFO - PID 13364 | 16:32:22 INFO - PID 13364 | __start_tp_report 16:32:22 INFO - PID 13364 | _x_x_mozilla_page_load 16:32:22 INFO - PID 13364 | _x_x_mozilla_page_load_details 16:32:22 INFO - PID 13364 | |i|pagename|runs| 16:32:22 INFO - PID 13364 | |0;ai-astar;107;110;108;109;111;110;111;110;110;113 16:32:22 INFO - PID 13364 | |1;audio-beat-detection;152;120;127;160;117;116;116;117;140;120 16:32:22 INFO - PID 13364 | |2;audio-dft;162;162;160;160;162;162;163;160;163;163 16:32:22 INFO - PID 13364 | |3;audio-fft;74;74;74;113;74;74;74;74;95;73 16:32:22 INFO - PID 13364 | |4;audio-oscillator;82;81;103;82;82;82;95;82;82;109 16:32:22 INFO - PID 13364 | |5;imaging-gaussian-blur;113;114;113;113;113;113;113;113;113;112 16:32:22 INFO - PID 13364 | |6;imaging-darkroom;146;146;147;147;146;146;150;147;147;146 16:32:22 INFO - PID 13364 | |7;imaging-desaturate;94;94;94;95;93;94;93;94;93;93 16:32:22 INFO - PID 13364 | |8;json-parse-financial;47;44;45;44;46;48;46;46;44;46 16:32:22 INFO - PID 13364 | |9;json-stringify-tinderbox;55;56;57;56;56;56;56;56;56;57 16:32:22 INFO - PID 13364 | |10;stanford-crypto-aes;78;78;77;78;89;79;77;78;79;79 16:32:22 INFO - PID 13364 | |11;stanford-crypto-ccm;127;122;121;121;120;121;121;122;167;132 16:32:22 INFO - PID 13364 | |12;stanford-crypto-pbkdf2;175;178;185;177;178;178;180;176;172;177 16:32:22 INFO - PID 13364 | |13;stanford-crypto-sha256-iterative;64;64;66;64;63;65;64;66;66;65 16:32:22 INFO - PID 13364 | __end_tp_report 16:32:22 INFO - PID 13364 | __start_cc_report 16:32:22 INFO - PID 13364 | _x_x_mozilla_cycle_collect,1679 16:32:22 INFO - PID 13364 | __end_cc_report 16:32:22 INFO - PID 13364 | __startTimestamp1494372742576__endTimestamp 16:32:22 INFO - PID 13364 | 16:32:22 INFO - PID 13364 | ------- Summary: start ------- 16:32:22 INFO - PID 13364 | Number of tests: 14 16:32:22 INFO - PID 13364 | 16:32:22 INFO - PID 13364 | [#0] ai-astar Cycles:10 Average:109.90 Median:110.00 stddev:1.66 (1.5%) stddev-sans-first:1.39 16:32:22 INFO - PID 13364 | Values: 107.0 110.0 108.0 109.0 111.0 110.0 111.0 110.0 110.0 113.0 16:32:22 INFO - PID 13364 | 16:32:22 INFO - PID 13364 | [#1] audio-beat-detection Cycles:10 Average:128.50 Median:123.50 stddev:16.33 (13.2%) stddev-sans-first:14.95 16:32:22 INFO - PID 13364 | Values: 152.0 120.0 127.0 160.0 117.0 116.0 116.0 117.0 140.0 120.0 16:32:22 INFO - PID 13364 | 16:32:22 INFO - PID 13364 | [#2] audio-dft Cycles:10 Average:161.70 Median:162.00 stddev:1.25 (0.8%) stddev-sans-first:1.32 16:32:22 INFO - PID 13364 | Values: 162.0 162.0 160.0 160.0 162.0 162.0 163.0 160.0 163.0 163.0 16:32:22 INFO - PID 13364 | 16:32:22 INFO - PID 13364 | [#3] audio-fft Cycles:10 Average:79.90 Median:74.00 stddev:13.40 (18.1%) stddev-sans-first:14.04 16:32:22 INFO - PID 13364 | Values: 74.0 74.0 74.0 113.0 74.0 74.0 74.0 74.0 95.0 73.0 16:32:22 INFO - PID 13364 | 16:32:22 INFO - PID 13364 | [#4] audio-oscillator Cycles:10 Average:88.00 Median:82.00 stddev:10.43 (12.7%) stddev-sans-first:10.84 16:32:22 INFO - PID 13364 | Values: 82.0 81.0 103.0 82.0 82.0 82.0 95.0 82.0 82.0 109.0 16:32:22 INFO - PID 13364 | 16:32:22 INFO - PID 13364 | [#5] imaging-gaussian-blur Cycles:10 Average:113.00 Median:113.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 16:32:22 INFO - PID 13364 | Values: 113.0 114.0 113.0 113.0 113.0 113.0 113.0 113.0 113.0 112.0 16:32:22 INFO - PID 13364 | 16:32:22 INFO - PID 13364 | [#6] imaging-darkroom Cycles:10 Average:146.80 Median:147.00 stddev:1.23 (0.8%) stddev-sans-first:1.27 16:32:22 INFO - PID 13364 | Values: 146.0 146.0 147.0 147.0 146.0 146.0 150.0 147.0 147.0 146.0 16:32:22 INFO - PID 13364 | 16:32:22 INFO - PID 13364 | [#7] imaging-desaturate Cycles:10 Average:93.70 Median:94.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 16:32:22 INFO - PID 13364 | Values: 94.0 94.0 94.0 95.0 93.0 94.0 93.0 94.0 93.0 93.0 16:32:22 INFO - PID 13364 | 16:32:22 INFO - PID 13364 | [#8] json-parse-financial Cycles:10 Average:45.60 Median:46.00 stddev:1.35 (2.9%) stddev-sans-first:1.33 16:32:22 INFO - PID 13364 | Values: 47.0 44.0 45.0 44.0 46.0 48.0 46.0 46.0 44.0 46.0 16:32:22 INFO - PID 13364 | 16:32:22 INFO - PID 13364 | [#9] json-stringify-tinderbox Cycles:10 Average:56.10 Median:56.00 stddev:0.57 (1.0%) stddev-sans-first:0.44 16:32:22 INFO - PID 13364 | Values: 55.0 56.0 57.0 56.0 56.0 56.0 56.0 56.0 56.0 57.0 16:32:22 INFO - PID 13364 | 16:32:22 INFO - PID 13364 | [#10] stanford-crypto-aes Cycles:10 Average:79.20 Median:78.50 stddev:3.52 (4.5%) stddev-sans-first:3.71 16:32:22 INFO - PID 13364 | Values: 78.0 78.0 77.0 78.0 89.0 79.0 77.0 78.0 79.0 79.0 16:32:22 INFO - PID 13364 | 16:32:22 INFO - PID 13364 | [#11] stanford-crypto-ccm Cycles:10 Average:127.40 Median:122.00 stddev:14.40 (11.8%) stddev-sans-first:15.27 16:32:22 INFO - PID 13364 | Values: 127.0 122.0 121.0 121.0 120.0 121.0 121.0 122.0 167.0 132.0 16:32:22 INFO - PID 13364 | 16:32:22 INFO - PID 13364 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.60 Median:178.00 stddev:3.37 (1.9%) stddev-sans-first:3.44 16:32:22 INFO - PID 13364 | Values: 175.0 178.0 185.0 177.0 178.0 178.0 180.0 176.0 172.0 177.0 16:32:22 INFO - PID 13364 | 16:32:22 INFO - PID 13364 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.70 Median:65.00 stddev:1.06 (1.6%) stddev-sans-first:1.09 16:32:22 INFO - PID 13364 | Values: 64.0 64.0 66.0 64.0 63.0 65.0 64.0 66.0 66.0 65.0 16:32:22 INFO - PID 13364 | -------- Summary: end -------- 16:32:22 INFO - PID 13364 | 16:32:23 INFO - TEST-INFO | 13364: exit 0 16:32:23 INFO - TEST-OK | kraken | took 60390ms 16:32:23 INFO - SUITE-END | took 1175s 16:32:24 INFO - Completed test suite (00:19:36) 16:32:24 INFO - javascript benchmark 16:32:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7614.965665923607, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3391.7456190785697, 3409.1988382730055, 3424.315136972606, 3425.383430982422, 3460.0484866661664, 5762.222100016496, 5883.264591926009, 5939.584229103959, 5954.940450595498, 5979.880402391949, 5881.264841894763, 5898.734556944937, 5971.581989260753, 6018.638881667092, 6042.57701960863, 4849.539293767104, 5071.391433028023, 5203.635745497817, 5207.687538747668, 5290.941811637668, 5252.028374750684, 5331.786728530874, 5337.359516857982, 5342.278792363039, 5342.439043900393, 5104.974475127638, 5187.740612969336, 5194.844154675366, 5225.477452254782, 5259.737013149327, 5233.502817232356, 5246.7901283948595, 5255.658382205144, 5291.391489978663, 5315.840524784263, 5223.582113430916, 5240.633155679104, 5254.52709256165, 5259.184826351921, 5271.683698978073, 5780.1907732917425, 5858.589898707092, 5879.32387775407, 5942.851428714273, 5958.702064896738, 6463.0, 6482.384173503509, 6484.6433446160445, 6486.318936511692, 6496.252930912911, 6496.187976502937, 6521.282658907517, 6522.673866306665, 6530.575512591714, 6536.379043990813, 5768.221290125864, 5916.940830591725, 6029.246344206974, 6034.336223015464, 6037.939620603782, 6456.322086180883, 6543.411093001558, 6554.213494380705, 6588.703508342136, 6593.86812263752, 6095.529019716752, 6176.166217560662, 6242.843928901723, 6292.874142517124, 6361.5546911716665, 6439.130717353191, 6439.8068057958335, 6464.25661048976, 6487.805365839033, 6504.642244676588, 6179.104029915637, 6273.153124328249, 6461.030845373231, 6464.709088091047, 6472.741090356333, 6308.148399965946, 6384.904226436607, 6387.776427825004, 6421.29365769765, 6435.292117867031, 4846.709197448165, 4849.248366503198, 4858.514148585114, 4861.465238823726, 4893.6085113191575, 4312.460942382202, 4318.503372112247, 4338.023944612437, 4351.804168812411, 4359.67302452311, 5897.144913987448, 5992.221011268594, 6037.969810150921, 6104.38956104386, 6115.602485838406, 6471.385218404338, 6486.545941784124, 6488.383603557654, 6488.448481878999, 6501.674916254168, 5992.131140984533, 6082.665453400105, 6095.024796032613, 6100.328963813977, 6108.35862234459, 5434.782608695607, 5516.3380394352935, 5550.47270509301, 5628.971855140698, 5652.576056795757, 6809.182898052265, 6848.7260509579055, 6849.828754281183, 6851.8972215415815, 6853.554518956252, 6529.67351632407, 6531.0, 6537.1501704778075, 6546.0, 6548.639824809681, 6419.069234960998, 6561.40947314744, 6655.035019921987, 6741.2921643226755, 6807.591544507345, 6327.557071004985, 6408.134901788386, 6414.775482858077, 6416.582922110048, 6421.64680942534, 6552.3775241352, 6552.967235163985, 6555.2133743949125, 6561.212654481302, 6563.868722625616, 6100.005955098508, 6108.664023478751, 6159.23009623797, 6190.164327815687, 6311.90532142009, 6283.59156654816, 6312.810615681537, 6336.619802811846, 6345.619262844244, 6353.205849268841, 6217.564770466024, 6269.435750782452, 6277.058441233851, 6278.152449419453, 6323.652199129092, 6119.2350956130485, 6154.599951003171, 6293.779717709858, 6352.872942541031, 6358.364163583789, 6072.271327440735, 6126.632402055891, 6157.32269450369, 6159.476444502088, 6168.444839964424], "value": 5879.928300630639, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [721.577876941989, 795.4988357334879, 814.4665244265007, 1130.2653275371013, 1152.1877076660955, 28633.713662863363, 36329.636703632954, 37036.0, 37151.44272835906, 37182.8140859296, 39169.0, 39668.20663586727, 39702.40446393307, 39764.403533947014, 39772.204555908866, 5732.5987180897355, 5839.240898683186, 7325.597092159943, 7455.925440745577, 7456.328930396263, 7187.7843664690145, 7316.085489313836, 7319.41444684424, 7337.2662733726465, 7342.375898048672, 7451.254874512532, 7484.101907771075, 7492.887606685904, 7497.100347958225, 7503.324700776929, 7363.631818409085, 7428.257174282609, 7434.962825185894, 7442.66508007141, 7449.068866391701, 7290.0158478605235, 7370.0, 7370.336669699753, 7372.926270737277, 7375.4099672026105, 34841.82579087114, 35054.298914021834, 35065.123371915906, 35125.82437087824, 35130.648693512994, 3434.3989801784587, 3451.9827400862837, 3466.722662187019, 3468.6704763047724, 3470.0804286864, 28214.15357539277, 28329.57505637417, 28420.85789571039, 28421.147365579065, 28467.57298640522, 3247.1070455624654, 3254.8372581370836, 3255.951160732591, 3269.5749552558254, 3283.6387997320276, 185931.0, 186099.0, 186243.06878465656, 186606.0, 186687.0, 47075.0, 47133.29300060433, 47165.52834471611, 47184.0, 47225.763871180425, 48292.0, 48430.0, 48518.5148148514, 48630.0, 48642.513574864504, 13746.862531374558, 13810.93094534521, 13812.585622431343, 13838.515651952133, 13855.86144138566, 9454.716358509118, 9634.566444510014, 11017.283876548, 11080.778384432266, 11095.334279943068, 12449.875501245053, 12457.937710311571, 12516.436760345807, 12542.557605875441, 12734.172278801849, 5290.0, 5386.380566234933, 5401.162819762943, 5426.0, 5461.1262198048125, 1054.3094273250879, 1088.5700148441322, 1091.8635170603675, 1091.9235653504338, 1095.5562996986234, 23838.761612384, 24507.87746061294, 24525.63211551828, 24812.751872481043, 29164.854175728986, 1124.5389390349917, 1132.326265871805, 1144.2333636463422, 1153.5789436855655, 1161.4192903548226, 12628.936855315664, 13029.543965961146, 13073.738525229443, 13314.0, 13357.332133393485, 234252.0, 235680.0, 235766.0, 236588.0, 237138.0, 49271.0, 49334.506654933706, 49396.0, 49499.25751113736, 49500.50499494959, 1448.1166488442043, 1453.4694836384856, 1470.0885451020222, 1495.9925200374146, 1498.8351281359041, 23213.303600892, 24122.517549648557, 24125.396865078514, 24229.757702423456, 24261.27216183517, 37021.44467832931, 37027.44458833174, 37050.814745926094, 37093.0, 37131.0, 202851.0, 204276.0, 204330.0, 205015.0, 205066.0, 31272.374552509278, 31337.8433107833, 33805.66194338025, 34610.65389346175, 34620.653793461744, 41224.587754122076, 43024.0, 43038.0, 43138.13723725445, 43262.78368608137, 31595.36809263756, 31759.364812703156, 31836.363272734878, 31935.0, 32594.674053259165, 31647.20881977969, 32033.0, 32122.0, 32452.0, 32500.83749581237, 37853.621463785006, 38126.428103579026, 38216.0, 38221.2355752889, 38298.425523616614, 30075.69924300729, 30442.543361850032, 30877.38245235038, 30988.0, 31602.839716028215, 24972.37569060788, 25326.87336563379, 25421.364465888502, 25622.0, 25844.870775646003], "value": 16503.111943578067, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [383.47081028181105, 441.38647280280395, 447.50550641541093, 459.0795455112499, 468.2998916619654, 19356.806431935693, 19830.20679172833, 19929.701054484194, 19976.900115499422, 20364.898175509137, 19100.713489297672, 19728.506787330323, 19963.800361996375, 20234.392968210977, 21684.2410515632, 5585.357683866367, 5750.166225897243, 5750.942490575083, 5752.137179423078, 5757.971210143944, 5295.364556253255, 5497.340319161706, 5524.171374293868, 5531.502164805167, 5535.25274087999, 5497.615166938316, 5500.50495455409, 5505.284313039299, 5507.807226747064, 5508.476694714016, 5535.833924982237, 5702.657840529581, 5743.770249190027, 5756.539476841864, 5761.366249712528, 5169.84936662106, 5205.947940520584, 5242.13504771716, 5248.422673505911, 5248.737563121828, 25421.491570168677, 26107.608385874228, 26172.0, 26408.339791505176, 26440.0, 2098.0, 2173.217641649005, 2178.063432723928, 2179.1174574297434, 2185.9562808743894, 17125.828741712674, 17281.30874765008, 17478.912605437017, 17535.210915508833, 17762.911185444118, 2197.450637340665, 2198.1756841184556, 2215.4018415028013, 2233.553289342138, 2256.9322920312416, 106066.0, 107587.46206268997, 108546.45726771395, 108868.99999999921, 115169.84830151674, 11293.830592541119, 11737.35444550558, 12120.45457954394, 12251.38743062861, 12342.506299747909, 17434.912825436044, 17900.820991789915, 17907.641847163246, 18039.72940405895, 18048.729269060976, 8534.146585341416, 8566.828663426697, 8786.912130878738, 8934.06192349807, 8963.731088067369, 7968.960155199303, 7991.041075071029, 8007.0, 8149.877751833727, 8168.632411541495, 7962.56205908669, 7994.560299183485, 8065.516069035877, 8087.0, 8089.191080891981, 2174.4346469917937, 2206.1837120265604, 2207.459172502747, 2309.2148669452467, 2340.414896275931, 1027.2141811514198, 1047.162270183865, 1052.7052425320921, 1052.889446608095, 1056.3609016545033, 18653.33666583354, 20188.697169542174, 20297.188112475334, 20626.174953002013, 20859.1656333748, 1047.2983101322131, 1053.2364036073784, 1056.0812093478723, 1058.629479682105, 1066.8826429092794, 11091.889081109248, 11111.777764444665, 11120.443977801078, 11122.721931951604, 11289.943550282196, 453039.0, 460771.0, 462686.0, 464084.0, 465996.0, 91893.0, 92190.0, 92588.53705731429, 93594.53202733943, 93805.53097234607, 1165.6328256599145, 1197.0902114393125, 1216.6106845809434, 1221.9694507637203, 1228.5208768580083, 16726.247318870435, 17254.96470211791, 17292.913535432242, 17576.82423175752, 17969.910150449163, 43092.337151342486, 43645.0, 43772.0, 44049.779751101036, 44057.77971110124, 55273.0, 55693.0, 56129.0, 56974.715126424104, 57503.712481437324, 8755.387122901337, 8799.47203167812, 8957.552122393985, 8978.236849867835, 9214.0, 12931.03017273727, 13098.279594622387, 13345.332733363488, 13364.532241371506, 13828.377723002288, 10987.450627468435, 11023.83464248053, 11041.834372484258, 11473.483693233658, 11513.42432878336, 10739.0, 10931.125509959025, 11017.56408854608, 11020.283681560673, 11132.55469781232, 12503.124781265224, 12572.937135314265, 13021.156278677472, 13060.659062483008, 13382.93308533451, 8940.374173807772, 8976.730698079067, 9161.541922903962, 9419.199368053793, 9521.714348569554, 11358.375289359165, 11526.884731152917, 11596.768064638829, 11615.5353785851, 11760.941195293968], "value": 9881.792855040441, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1345.0, 1376.181172202539, 1396.6159306190802, 1402.845686974432, 1412.8799052080572, 10953.945230273848, 11479.139064570149, 11480.598179063734, 11488.310701357925, 11500.252483588562, 11063.944680276607, 11296.378699171542, 11313.264637798537, 11384.829227561593, 11403.828942565848, 2260.0846657103857, 2298.839081264312, 2300.25241796416, 2301.827362947777, 2307.9844868257996, 1910.4746194796473, 1991.2930909527126, 2218.4010317214425, 2260.062074239189, 2278.6354183330673, 2196.231319038332, 2273.6703178039174, 2277.6811246425514, 2278.943026424336, 2283.5318759654315, 2064.8554601177925, 2069.782672819355, 2085.8435617328687, 2090.1848279171136, 2264.8867556622267, 1910.9713354299697, 1975.7157847399162, 2155.2564365293947, 2253.143805353955, 2263.8868056597103, 11211.607593734261, 11297.435128243555, 11308.208425410225, 11387.886121138765, 11426.600068997544, 6267.153934218868, 6527.869442611169, 6530.771423000172, 6540.803775886683, 6548.476121910236, 3765.811709414546, 3925.2738243424774, 3964.7422917510557, 3987.920241595181, 3999.14018486024, 1237.789575772121, 1250.2810883741886, 1277.47623474376, 1325.4631874091008, 1344.7646661834237, 17642.0, 17807.643847123116, 17832.197551110232, 17840.107994600217, 17850.46448606531, 5227.634065615446, 5265.710385928811, 5464.0, 5464.453554644504, 5497.862553436196, 12723.809142862865, 12736.108472407028, 12744.80882786759, 12746.681332966564, 12768.170068945488, 6316.178896743486, 6402.967985160138, 6407.935920640827, 6408.0, 6413.903791443132, 5953.880922381615, 5969.552283578749, 6090.390960903874, 6100.755969761249, 6241.625502469867, 4136.772477513766, 4272.530021697611, 4303.354496825501, 4318.207943703328, 4320.351947207944, 1194.235689158939, 1222.1872454817624, 1229.9938500307442, 1232.9260244385366, 1239.8822111899356, 848.325581162977, 855.6320782063773, 876.5047748022347, 883.9216156289317, 905.1536813079791, 6396.42432181106, 6405.615663060231, 6448.580842245333, 6483.059956306403, 6504.642244676494, 635.2408871398727, 644.5005121031145, 647.0746832030148, 654.8559316950264, 661.2957200581366, 4111.814968326433, 4177.519585247735, 4184.895377615584, 4200.411942328076, 4233.597808208216, 15039.0, 15722.606934826492, 15754.606134846721, 15779.842201577838, 15831.208439577975, 14945.925270373578, 15572.688546229012, 15809.525714228592, 15903.0, 15973.520794376187, 9479.526023698925, 9506.33455658111, 9518.809623807623, 9618.663346782829, 9619.470929098827, 6845.931540684529, 6912.239653638197, 7064.364207221375, 7071.7878463646175, 7073.6816843243205, 13692.0, 13988.300584970913, 14011.929940350234, 14105.506307279196, 14120.78818817698, 14171.291435427982, 14244.715105698035, 14301.499447519287, 14482.637934051732, 14664.633384165481, 8778.4294020891, 8793.29653627721, 8844.955775221082, 8916.33127515426, 8931.821363572562, 10882.727931801768, 11022.063124634496, 11087.889121108685, 11101.056410205223, 11108.88891111111, 9397.530123493661, 9529.809403811747, 9561.713148605553, 9621.759456013375, 9725.076117769084, 9187.816243674955, 9406.200472959876, 9427.434353938786, 9436.575354109218, 9509.572069256761, 8331.83336333282, 8570.314374850122, 8589.312854971713, 8594.742157735278, 8605.827883442422, 6308.211473565804, 6311.96844015777, 6334.714937827716, 6410.4871610272, 6600.1089852869245, 6014.1580178775885, 6096.542759292982, 6177.81466556004, 6302.338254483213, 6311.305756366888], "value": 5302.605602055463, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [414.6537641069707, 416.52932186629107, 427.16702430261, 432.401124442647, 433.32088158386273, 5677.01956472852, 5912.733926973286, 5943.970280148598, 5951.404859514047, 5966.671833049191, 5770.855728606787, 5801.013827649299, 5821.301443826736, 5826.096954971976, 5839.386864379243, 4532.614727748137, 4556.476005259405, 4562.817487300504, 4569.885752856172, 4572.359869618256, 4467.262901621245, 4530.116627257686, 4558.430196225472, 4571.679982401233, 4600.217962946307, 4522.728636281834, 4527.954720452803, 4528.343390208434, 4530.773461326937, 4532.8866778330475, 4519.5932366087045, 4537.818487260505, 4546.749074411779, 4550.453945526525, 4552.704074235164, 4044.5955404459614, 4061.346349198507, 4123.958760412417, 4137.462129923121, 4145.357469592218, 5620.690862002588, 5635.464630860061, 5669.0, 5690.886182276331, 5712.485876271156, 5401.945980540184, 5414.3232095988005, 5420.647657902224, 5422.376426710939, 5437.374701909292, 6087.178230938811, 6113.363344691249, 6145.907811382833, 6222.257767825016, 6235.158253635746, 4642.442906851199, 4647.1402790483535, 4654.138984287883, 4671.85984420468, 4702.0, 6113.0, 6278.0, 6438.613683178977, 6490.288546079467, 6504.682611867934, 4356.76037817923, 4730.645201609893, 4841.854744357604, 4872.3909511311085, 4908.846421091041, 4927.8665906841625, 4952.752362381939, 4974.701517908937, 4976.751162441863, 4996.401871027046, 5346.625736198506, 5435.374931882884, 5466.098093814476, 5512.944870551243, 5517.503424691797, 6215.595986260879, 6225.159603453566, 6229.283632382334, 6244.937550624436, 6277.372262773686, 1708.63264398155, 1713.9742903856452, 1721.8708596855286, 1724.1206984437777, 1724.9564013771737, 1137.192593258779, 1203.5967950736426, 1213.2477863724548, 1223.7246619510538, 1231.950721971129, 5955.285365756137, 5962.25471816023, 6001.879962400815, 6012.969935150384, 6017.11895827189, 6353.36466353361, 6356.650384228912, 6358.427741503287, 6359.554831161774, 6379.5215358847345, 5824.737886795105, 6031.0, 6074.331823499411, 6075.936711075544, 6077.787277445356, 3372.898813035613, 3373.224158443572, 3374.3420033093803, 3392.3208012033706, 3397.1507123219194, 6518.641474718936, 6700.83247918806, 6748.156480439945, 6787.6266805325195, 6795.93204067953, 6616.636085015274, 6623.4038936495945, 6642.302558231316, 6657.3342665733035, 6668.699908504129, 6326.810195694136, 6416.454601358843, 6491.0, 6653.600783952881, 6687.699053542505, 6372.044193371032, 6372.139761132188, 6372.5220608455475, 6380.7447702091395, 6390.64851433157, 6141.877162456815, 6294.1188233648245, 6310.075426994627, 6352.809415717536, 6398.616083035033, 6714.3957043866285, 6725.932740672726, 6728.461723062242, 6740.629265390255, 6748.7300507979135, 4891.290762839297, 5007.574356179765, 5010.774515146754, 5031.169856973558, 5041.6470847040355, 6435.935640643534, 6460.612363258219, 6462.806115816533, 6468.38550337736, 6468.417842394207, 6008.399160083956, 6235.719392627433, 6260.436560709558, 6263.655498947602, 6276.717547710273, 6208.192934918432, 6252.874942501216, 6262.248530176408, 6262.34245404226, 6274.466670333073, 6202.937970620236, 6222.542095872196, 6226.719797609027, 6233.376662333731, 6256.717372938446, 6079.0, 6245.687715614292, 6254.687265636791, 6269.247690277196, 6269.404406581368, 6086.756529738761, 6086.817395478142, 6105.16110008848, 6113.29697084841, 6118.694065296806], "value": 4832.728712205396, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [860.2601762484264, 870.7648934787611, 880.4057261348588, 881.8544940084886, 895.5343221524805, 15952.601184970366, 16168.83831161688, 16380.017198968055, 16390.01659900407, 16434.096124713153, 15516.30176642051, 15706.607334816606, 15727.66314863238, 15832.208389580532, 15871.285792139353, 986.5264672956966, 1002.293383164867, 1003.8142943555429, 1004.4877112672553, 1004.6333088422746, 927.8190752803206, 998.3760149906309, 1007.0584003956287, 1007.6422869881196, 1007.8135544924213, 991.3159919655451, 1016.232744278069, 1025.4565080507343, 1028.2545154762774, 1053.420618659738, 1023.6468418395641, 1023.923205759571, 1023.9436830974294, 1025.0364657222187, 1025.1235193909213, 760.1676164599754, 761.8095476130967, 761.8285885675679, 762.142589586715, 762.2034973452755, 15923.44267950616, 16055.598610034725, 16089.356425742957, 16755.329786808514, 17064.488065357935, 17486.737698934525, 17643.647127057517, 17750.556236094202, 17786.110694465224, 18149.637007259913, 12988.610341689764, 13137.934310328483, 13217.73564528704, 14654.56036318912, 14680.119192848357, 3400.6429324920773, 3420.2817408344276, 3429.8285085745615, 3434.295969326282, 3434.4161492546327, 82094.58952705258, 82892.58553707253, 92344.0, 92637.0, 93331.0, 14556.49052283165, 14705.264736763333, 14831.0, 14902.1803800792, 14918.4778532753, 26661.333466663487, 26859.32851678724, 27160.320991974964, 27355.72644273532, 27383.86308068447, 7950.284474397332, 8082.9191708083345, 8141.51150930946, 8160.795980100545, 8164.265216130577, 3777.4522694209736, 3822.2546603412625, 3832.7317087803585, 3833.5783063862955, 3856.382978723391, 4479.10417916418, 4549.454065512094, 4561.24739417999, 4562.452505699337, 4569.360289559464, 839.4249938791947, 860.6428332242175, 867.8741582470507, 871.2942516561606, 879.7360791762574, 708.1113202930289, 721.1670520548774, 726.963651817407, 736.4255880413286, 762.2111114996063, 11427.657170284905, 12172.513099475922, 12909.54816581415, 13310.334483275798, 13685.521006764715, 703.3669697272394, 711.3953139831102, 723.3345322303388, 730.3791776989516, 743.4758495260828, 5223.347081614798, 5410.065632428487, 5661.0, 5695.060315047971, 5706.28671416073, 78474.05178145657, 79948.60025699834, 80719.59640201762, 80828.59585702034, 82608.58695706484, 65961.67019164874, 66156.0, 66211.0, 66315.0, 66835.33164668389, 60052.0, 60588.0, 60955.69522152361, 60994.0, 61428.0, 11093.454861631675, 11144.498497567467, 11773.882261177621, 11833.70415739579, 11941.52233910634, 31839.0, 32002.0, 32718.83640581782, 32964.67035329712, 34091.0, 38827.0, 39482.012949676486, 39648.603513965645, 40470.392944106454, 40948.79525602353, 14194.148351098554, 14284.142951422948, 14394.064385814889, 14412.711745764816, 14568.271586420424, 15978.840211597735, 16227.594310142342, 16278.755818662492, 16318.755218671968, 16570.254338554554, 15555.611109722347, 15608.0, 15626.921865390601, 15701.607459813597, 15749.897507174388, 14872.553823385315, 15306.08163510193, 15341.539753807403, 15532.844671553592, 15689.450869219523, 15141.0, 15270.847291526943, 15310.846891531388, 15311.923440382727, 15604.453844115402, 10959.780804384027, 11140.554377825121, 11450.94274528622, 11466.082713382742, 11570.19008669385, 12475.625731228078, 12885.0, 13018.609441716764, 13029.869701302865, 13085.607431777062], "value": 7934.579266645386, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1472.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 110.0, 108.0, 109.0, 111.0, 110.0, 111.0, 110.0, 110.0, 113.0], "value": 109.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 120.0, 127.0, 160.0, 117.0, 116.0, 116.0, 117.0, 140.0, 120.0], "value": 128.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 160.0, 160.0, 162.0, 162.0, 163.0, 160.0, 163.0, 163.0], "value": 161.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 74.0, 113.0, 74.0, 74.0, 74.0, 74.0, 95.0, 73.0], "value": 79.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 103.0, 82.0, 82.0, 82.0, 95.0, 82.0, 82.0, 109.0], "value": 88.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 112.0], "value": 113.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 147.0, 147.0, 146.0, 146.0, 150.0, 147.0, 147.0, 146.0], "value": 146.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 95.0, 93.0, 94.0, 93.0, 94.0, 93.0, 93.0], "value": 93.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [47.0, 44.0, 45.0, 44.0, 46.0, 48.0, 46.0, 46.0, 44.0, 46.0], "value": 45.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 57.0, 56.0, 56.0, 56.0, 56.0, 56.0, 56.0, 57.0], "value": 56.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 78.0, 77.0, 78.0, 89.0, 79.0, 77.0, 78.0, 79.0, 79.0], "value": 79.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 122.0, 121.0, 121.0, 120.0, 121.0, 121.0, 122.0, 167.0, 132.0], "value": 127.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 178.0, 185.0, 177.0, 178.0, 178.0, 180.0, 176.0, 172.0, 177.0], "value": 177.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 66.0, 64.0, 63.0, 65.0, 64.0, 66.0, 66.0, 65.0], "value": 64.7, "unit": "score"}]}]} 16:32:24 INFO - Return code: 0 16:32:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:32:24 INFO - # TBPL SUCCESS # 16:32:24 INFO - Running post-action listener: _resource_record_post_action 16:32:24 INFO - [mozharness: 2017-05-09 23:32:24.372109Z] Finished run-tests step (success) 16:32:24 INFO - Running post-run listener: _resource_record_post_run 16:32:24 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 8192; Write bytes: 376336384; Read time: 228; Write time: 256684 16:32:24 INFO - TinderboxPrint: CPU usage
12.9% 16:32:24 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 228 16:32:24 INFO - TinderboxPrint: I/O write bytes / time
376,336,384 / 256,684 16:32:24 INFO - TinderboxPrint: CPU idle
8,303.4 (87.1%) 16:32:24 INFO - TinderboxPrint: CPU user
1,182.4 (12.4%) 16:32:24 INFO - TinderboxPrint: Swap in / out
0 / 0 16:32:24 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 111656960; Read time: 0; Write time: 92540 16:32:24 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 4096; Write bytes: 264679424; Read time: 220; Write time: 164144 16:32:25 INFO - Running post-run listener: _upload_blobber_files 16:32:25 INFO - Blob upload gear active. 16:32:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:32:25 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 16:32:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:32:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:32:25 INFO - (blobuploader) - INFO - Open directory for files ... 16:32:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:32:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:32:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:32:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:32:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:32:29 INFO - (blobuploader) - INFO - Done attempting. 16:32:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:32:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:32:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:32:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:32:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:32:30 INFO - (blobuploader) - INFO - Done attempting. 16:32:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:32:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:32:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:32:31 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:32:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:32:31 INFO - (blobuploader) - INFO - Done attempting. 16:32:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:32:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:32:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:32:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:32:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:32:31 INFO - (blobuploader) - INFO - Done attempting. 16:32:31 INFO - (blobuploader) - INFO - Iteration through files over. 16:32:31 INFO - Return code: 0 16:32:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:32:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:32:31 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a203f062b01b933e71b45f1d17c66f5138a1e104552e0528e5e52e5826f42fc233830b8de4029b30717758de5fd67e010697e0e18470e2b91b137e1d7c18ea51", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ab4cc5a9edc6edb53fe549660a99c97ea49c5912528ca7a462dec800be2e07f40327985e16e499b5f63c130e764fa4abc57423a5e53bced8a5e11e62d60afd74", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6a44380a811be27cc3a6042ce50792f7d2bb76ebb8aec4861140a8ad1188b0c0cc263bb675d399e05db7e479649f632b2e1eb4ae7847c2d3891e43b3e694f38c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0cbc69ba1a8847671ba27362d523ab208879dcee8e62668c7939c7afaedb2b151d85036e9d3ecfe423480698a78df01903cfee4a769e7562360d9c66ef6678b2"} 16:32:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:32:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:32:31 INFO - Contents: 16:32:31 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a203f062b01b933e71b45f1d17c66f5138a1e104552e0528e5e52e5826f42fc233830b8de4029b30717758de5fd67e010697e0e18470e2b91b137e1d7c18ea51", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ab4cc5a9edc6edb53fe549660a99c97ea49c5912528ca7a462dec800be2e07f40327985e16e499b5f63c130e764fa4abc57423a5e53bced8a5e11e62d60afd74", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6a44380a811be27cc3a6042ce50792f7d2bb76ebb8aec4861140a8ad1188b0c0cc263bb675d399e05db7e479649f632b2e1eb4ae7847c2d3891e43b3e694f38c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0cbc69ba1a8847671ba27362d523ab208879dcee8e62668c7939c7afaedb2b151d85036e9d3ecfe423480698a78df01903cfee4a769e7562360d9c66ef6678b2"} 16:32:31 INFO - Running post-run listener: copy_logs_to_upload_dir 16:32:31 INFO - Copying logs to upload dir... 16:32:31 INFO - mkdir: /builds/slave/test/build/upload/logs 16:32:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1248.174688 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2017-05-09 16:32:31.161589) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-09 16:32:31.165801) ========= 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=fa44defc139c4ba8574fe040000001db-1494371501.601023-989996121 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a203f062b01b933e71b45f1d17c66f5138a1e104552e0528e5e52e5826f42fc233830b8de4029b30717758de5fd67e010697e0e18470e2b91b137e1d7c18ea51", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ab4cc5a9edc6edb53fe549660a99c97ea49c5912528ca7a462dec800be2e07f40327985e16e499b5f63c130e764fa4abc57423a5e53bced8a5e11e62d60afd74", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6a44380a811be27cc3a6042ce50792f7d2bb76ebb8aec4861140a8ad1188b0c0cc263bb675d399e05db7e479649f632b2e1eb4ae7847c2d3891e43b3e694f38c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0cbc69ba1a8847671ba27362d523ab208879dcee8e62668c7939c7afaedb2b151d85036e9d3ecfe423480698a78df01903cfee4a769e7562360d9c66ef6678b2"} build_url:https://queue.taskcluster.net/v1/task/HG-JkYyNT7CpAr__TPkPxg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011214 build_url: 'https://queue.taskcluster.net/v1/task/HG-JkYyNT7CpAr__TPkPxg/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a203f062b01b933e71b45f1d17c66f5138a1e104552e0528e5e52e5826f42fc233830b8de4029b30717758de5fd67e010697e0e18470e2b91b137e1d7c18ea51", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ab4cc5a9edc6edb53fe549660a99c97ea49c5912528ca7a462dec800be2e07f40327985e16e499b5f63c130e764fa4abc57423a5e53bced8a5e11e62d60afd74", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6a44380a811be27cc3a6042ce50792f7d2bb76ebb8aec4861140a8ad1188b0c0cc263bb675d399e05db7e479649f632b2e1eb4ae7847c2d3891e43b3e694f38c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0cbc69ba1a8847671ba27362d523ab208879dcee8e62668c7939c7afaedb2b151d85036e9d3ecfe423480698a78df01903cfee4a769e7562360d9c66ef6678b2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-09 16:32:31.193990) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-09 16:32:31.194294) ========= 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=fa44defc139c4ba8574fe040000001db-1494371501.601023-989996121 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004420 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-09 16:32:31.280454) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-09 16:32:31.280841) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-09 16:32:31.281190) ========= ========= Total master_lag: 0.17 =========