builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-027 starttime: 1491986391.13 results: success (0) revision: d76bbedb3cf4550b4805b18d51afa7f115047623 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-12 01:39:51.126252) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-12 01:39:51.126698) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-12 01:39:51.126981) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-12 01:39:51.141745) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-12 01:39:51.142027) ========= 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=1f5564757af27664ca02d71f000001b0-1491986390.706528-1566323919 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-04-12 01:39:51-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 45.8M=0s 2017-04-12 01:39:51 (45.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.080770 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-12 01:39:51.239003) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-12 01:39:51.239515) ========= 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=1f5564757af27664ca02d71f000001b0-1491986390.706528-1566323919 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017846 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-12 01:39:51.290745) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-12 01:39:51.291317) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev d76bbedb3cf4550b4805b18d51afa7f115047623 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev d76bbedb3cf4550b4805b18d51afa7f115047623 --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=1f5564757af27664ca02d71f000001b0-1491986390.706528-1566323919 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-04-12 01:39:51,543 truncating revision to first 12 chars 2017-04-12 01:39:51,543 Setting DEBUG logging. 2017-04-12 01:39:51,543 attempt 1/10 2017-04-12 01:39:51,543 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/d76bbedb3cf4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-12 01:39:52,176 unpacking tar archive at: mozilla-aurora-d76bbedb3cf4/testing/mozharness/ program finished with exit code 0 elapsedTime=1.010824 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-12 01:39:52.316208) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-12 01:39:52.316523) ========= script_repo_revision: d76bbedb3cf4550b4805b18d51afa7f115047623 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-12 01:39:52.316894) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-12 01:39:52.317154) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-12 01:39:52.326025) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2017-04-12 01:39:52.326412) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=1f5564757af27664ca02d71f000001b0-1491986390.706528-1566323919 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 01:39:52 INFO - MultiFileLogger online at 20170412 01:39:52 in /builds/slave/test 01:39:52 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora-Non-PGO 01:39:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:39:52 INFO - {'append_to_log': False, 01:39:52 INFO - 'base_work_dir': '/builds/slave/test', 01:39:52 INFO - 'blob_upload_branch': 'Mozilla-Aurora-Non-PGO', 01:39:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:39:52 INFO - 'branch': 'Mozilla-Aurora-Non-PGO', 01:39:52 INFO - 'buildbot_json_path': 'buildprops.json', 01:39:52 INFO - 'config_files': ('talos/linux_config.py',), 01:39:52 INFO - 'default_actions': ('clobber', 01:39:52 INFO - 'read-buildbot-config', 01:39:52 INFO - 'download-and-extract', 01:39:52 INFO - 'populate-webroot', 01:39:52 INFO - 'create-virtualenv', 01:39:52 INFO - 'install', 01:39:52 INFO - 'run-tests'), 01:39:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:39:52 INFO - 'download_minidump_stackwalk': True, 01:39:52 INFO - 'download_symbols': 'ondemand', 01:39:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:39:52 INFO - 'tooltool.py': '/tools/tooltool.py', 01:39:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:39:52 INFO - '/tools/misc-python/virtualenv.py')}, 01:39:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:39:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:39:52 INFO - 'gecko_profile': False, 01:39:52 INFO - 'gecko_profile_interval': 0, 01:39:52 INFO - 'installer_path': 'installer.exe', 01:39:52 INFO - 'log_level': 'info', 01:39:52 INFO - 'log_name': 'talos', 01:39:52 INFO - 'log_to_console': True, 01:39:52 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 01:39:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 01:39:52 INFO - 'opt_config_files': (), 01:39:52 INFO - 'pip_index': False, 01:39:52 INFO - 'suite': 'dromaeojs', 01:39:52 INFO - 'system_bits': '32', 01:39:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:39:52 INFO - 'title': 'talos-linux64-ix-027', 01:39:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:39:52 INFO - 'use_talos_json': True, 01:39:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:39:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:39:52 INFO - 'work_dir': 'build'} 01:39:52 INFO - [mozharness: 2017-04-12 08:39:52.675110Z] Running clobber step. 01:39:52 INFO - Running pre-action listener: _resource_record_pre_action 01:39:52 INFO - Running main action method: clobber 01:39:52 INFO - rmtree: /builds/slave/test/build 01:39:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:39:54 INFO - Running post-action listener: _resource_record_post_action 01:39:54 INFO - [mozharness: 2017-04-12 08:39:54.248459Z] Finished clobber step (success) 01:39:54 INFO - [mozharness: 2017-04-12 08:39:54.248557Z] Running read-buildbot-config step. 01:39:54 INFO - Running pre-action listener: _resource_record_pre_action 01:39:54 INFO - Running main action method: read_buildbot_config 01:39:54 INFO - Using buildbot properties: 01:39:54 INFO - { 01:39:54 INFO - "project": "", 01:39:54 INFO - "product": "firefox", 01:39:54 INFO - "who": "nobody@mozilla.org", 01:39:54 INFO - "installer_path": "public/build/target.tar.bz2", 01:39:54 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 01:39:54 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-aurora", 01:39:54 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora talos dromaeojs", 01:39:54 INFO - "stage_platform": "linux64", 01:39:54 INFO - "basedir": "/builds/slave/test", 01:39:54 INFO - "buildnumber": 129, 01:39:54 INFO - "platform": "ubuntu64_hw", 01:39:54 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 01:39:54 INFO - "slavebuilddir": "test", 01:39:54 INFO - "taskId": "SjyFXQqPT_2bD_htSV1mXA", 01:39:54 INFO - "branch": "mozilla-aurora", 01:39:54 INFO - "script_repo_revision": "production", 01:39:54 INFO - "revision": "d76bbedb3cf4550b4805b18d51afa7f115047623", 01:39:54 INFO - "slavename": "talos-linux64-ix-027", 01:39:54 INFO - "repo_path": "releases/mozilla-aurora" 01:39:54 INFO - } 01:39:54 INFO - Finding installer, test and symbols from parent task. 01:39:54 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SjyFXQqPT_2bD_htSV1mXA'}, attempt #1 01:39:54 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SjyFXQqPT_2bD_htSV1mXA'}, attempt #1 01:39:55 INFO - Task dependencies: KeVv12r4Q1ed-jtV1j3DjA 01:39:55 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KeVv12r4Q1ed-jtV1j3DjA'}, attempt #1 01:39:55 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/KeVv12r4Q1ed-jtV1j3DjA/artifacts/public/build/target.tar.bz2 01:39:55 INFO - Set test_url: https://queue.taskcluster.net/v1/task/KeVv12r4Q1ed-jtV1j3DjA/artifacts/public/build/test_packages.json 01:39:55 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/KeVv12r4Q1ed-jtV1j3DjA/artifacts/public/build/target.crashreporter-symbols.zip 01:39:55 INFO - Running post-action listener: _resource_record_post_action 01:39:55 INFO - [mozharness: 2017-04-12 08:39:55.625789Z] Finished read-buildbot-config step (success) 01:39:55 INFO - [mozharness: 2017-04-12 08:39:55.626006Z] Running download-and-extract step. 01:39:55 INFO - Running pre-action listener: _resource_record_pre_action 01:39:55 INFO - Running main action method: download_and_extract 01:39:55 INFO - mkdir: /builds/slave/test/build/tests 01:39:55 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')]} 01:39:55 INFO - trying https://queue.taskcluster.net/v1/task/KeVv12r4Q1ed-jtV1j3DjA/artifacts/public/build/target.test_packages.json 01:39:55 INFO - Downloading https://queue.taskcluster.net/v1/task/KeVv12r4Q1ed-jtV1j3DjA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 01:39:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KeVv12r4Q1ed-jtV1j3DjA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 01:39:56 INFO - Downloaded 896 bytes. 01:39:56 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 01:39:56 INFO - Using the following test package requirements: 01:39:56 INFO - {u'common': [u'target.common.tests.zip'], 01:39:56 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 01:39:56 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 01:39:56 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 01:39:56 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 01:39:56 INFO - u'mozbase': [u'target.common.tests.zip'], 01:39:56 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 01:39:56 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 01:39:56 INFO - u'web-platform': [u'target.common.tests.zip', 01:39:56 INFO - u'target.web-platform.tests.zip'], 01:39:56 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 01:39:56 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 01:39:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KeVv12r4Q1ed-jtV1j3DjA/artifacts/public/build/target.common.tests.zip 01:39:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KeVv12r4Q1ed-jtV1j3DjA/artifacts/public/build/target.common.tests.zip'}, attempt #1 01:39:56 INFO - Fetch https://queue.taskcluster.net/v1/task/KeVv12r4Q1ed-jtV1j3DjA/artifacts/public/build/target.common.tests.zip into memory 01:39:57 INFO - Expected file size: 59540781 01:39:57 INFO - Obtained file size: 59540781 01:40:06 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 01:40:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KeVv12r4Q1ed-jtV1j3DjA/artifacts/public/build/target.common.tests.zip 01:40:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KeVv12r4Q1ed-jtV1j3DjA/artifacts/public/build/target.common.tests.zip'}, attempt #1 01:40:06 INFO - Fetch https://queue.taskcluster.net/v1/task/KeVv12r4Q1ed-jtV1j3DjA/artifacts/public/build/target.common.tests.zip into memory 01:40:08 INFO - Expected file size: 59540781 01:40:08 INFO - Obtained file size: 59540781 01:40:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KeVv12r4Q1ed-jtV1j3DjA/artifacts/public/build/target.talos.tests.zip 01:40:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KeVv12r4Q1ed-jtV1j3DjA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 01:40:18 INFO - Fetch https://queue.taskcluster.net/v1/task/KeVv12r4Q1ed-jtV1j3DjA/artifacts/public/build/target.talos.tests.zip into memory 01:40:19 INFO - Expected file size: 14404424 01:40:19 INFO - Obtained file size: 14404424 01:40:19 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')]} 01:40:19 INFO - trying https://queue.taskcluster.net/v1/task/KeVv12r4Q1ed-jtV1j3DjA/artifacts/public/build/target.tar.bz2 01:40:19 INFO - Downloading https://queue.taskcluster.net/v1/task/KeVv12r4Q1ed-jtV1j3DjA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 01:40:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KeVv12r4Q1ed-jtV1j3DjA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 01:40:20 INFO - Downloaded 66777666 bytes. 01:40:20 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KeVv12r4Q1ed-jtV1j3DjA/artifacts/public/build/target.tar.bz2 01:40:20 INFO - mkdir: /builds/slave/test/properties 01:40:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:40:20 INFO - Writing to file /builds/slave/test/properties/build_url 01:40:20 INFO - Contents: 01:40:20 INFO - build_url:https://queue.taskcluster.net/v1/task/KeVv12r4Q1ed-jtV1j3DjA/artifacts/public/build/target.tar.bz2 01:40:20 INFO - Running post-action listener: _resource_record_post_action 01:40:20 INFO - Running post-action listener: set_extra_try_arguments 01:40:20 INFO - [mozharness: 2017-04-12 08:40:20.800568Z] Finished download-and-extract step (success) 01:40:20 INFO - [mozharness: 2017-04-12 08:40:20.800664Z] Running populate-webroot step. 01:40:20 INFO - Running pre-action listener: _resource_record_pre_action 01:40:20 INFO - Running main action method: populate_webroot 01:40:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:40:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:40:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:40:20 INFO - '--tppagecycles', 01:40:20 INFO - '7'], 01:40:20 INFO - 'tests': ['tsvgm']}}, 01:40:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:40:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:40:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:40:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:40:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:40:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:40:20 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 01:40:20 INFO - 'talos_options': ['--disable-e10s'], 01:40:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:40:20 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 01:40:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:40:20 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 01:40:20 INFO - 'talos_options': ['--disable-e10s'], 01:40:20 INFO - 'tests': ['damp', 'tps']}, 01:40:20 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 01:40:20 INFO - 'tests': ['damp', 'tps']}, 01:40:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:40:20 INFO - 'tests': ['dromaeo_dom']}, 01:40:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:40:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 01:40:20 INFO - 'tests': ['basic_compositor_video']}, 01:40:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 01:40:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:40:20 INFO - 'tests': ['a11yr', 01:40:20 INFO - 'ts_paint', 01:40:20 INFO - 'tpaint', 01:40:20 INFO - 'sessionrestore', 01:40:20 INFO - 'sessionrestore_no_auto_restore', 01:40:20 INFO - 'tabpaint']}, 01:40:20 INFO - 'other-e10s': {'tests': ['a11yr', 01:40:20 INFO - 'ts_paint', 01:40:20 INFO - 'tpaint', 01:40:20 INFO - 'sessionrestore', 01:40:20 INFO - 'sessionrestore_no_auto_restore', 01:40:20 INFO - 'tabpaint']}, 01:40:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:40:20 INFO - 'ts_paint', 01:40:20 INFO - 'tpaint', 01:40:20 INFO - 'sessionrestore', 01:40:20 INFO - 'sessionrestore_no_auto_restore', 01:40:20 INFO - 'tabpaint']}, 01:40:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:40:20 INFO - 'ts_paint', 01:40:20 INFO - 'tpaint', 01:40:20 INFO - 'sessionrestore', 01:40:20 INFO - 'sessionrestore_no_auto_restore', 01:40:20 INFO - 'tabpaint']}, 01:40:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:40:20 INFO - 'tests': ['a11yr', 01:40:20 INFO - 'ts_paint', 01:40:20 INFO - 'tpaint', 01:40:20 INFO - 'sessionrestore', 01:40:20 INFO - 'sessionrestore_no_auto_restore', 01:40:20 INFO - 'tabpaint']}, 01:40:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:40:20 INFO - 'tests': ['a11yr', 01:40:20 INFO - 'ts_paint', 01:40:20 INFO - 'tpaint', 01:40:20 INFO - 'sessionrestore', 01:40:20 INFO - 'sessionrestore_no_auto_restore', 01:40:20 INFO - 'tabpaint']}, 01:40:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:40:20 INFO - 'tests': ['tsvgx', 01:40:20 INFO - 'tsvgr_opacity', 01:40:20 INFO - 'tart', 01:40:20 INFO - 'tscrollx', 01:40:20 INFO - 'cart', 01:40:20 INFO - 'tsvg_static']}, 01:40:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:40:20 INFO - 'tsvgr_opacity', 01:40:20 INFO - 'tart', 01:40:20 INFO - 'tscrollx', 01:40:20 INFO - 'cart', 01:40:20 INFO - 'tsvg_static']}, 01:40:20 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 01:40:20 INFO - 'talos_options': ['--disable-e10s'], 01:40:20 INFO - 'tests': ['tp5o']}, 01:40:20 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 01:40:20 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 01:40:20 INFO - 'talos_options': ['--disable-e10s', 01:40:20 INFO - '--xperf_path', 01:40:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:40:20 INFO - 'tests': ['tp5n']}, 01:40:20 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 01:40:20 INFO - 'talos_options': ['--xperf_path', 01:40:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:40:20 INFO - 'tests': ['tp5n']}}, 01:40:20 INFO - 'talos.zip': {'path': '', 01:40:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:40:20 INFO - Running post-action listener: _resource_record_post_action 01:40:20 INFO - [mozharness: 2017-04-12 08:40:20.813673Z] Finished populate-webroot step (success) 01:40:20 INFO - [mozharness: 2017-04-12 08:40:20.813763Z] Running create-virtualenv step. 01:40:20 INFO - Running pre-action listener: _resource_record_pre_action 01:40:20 INFO - Running main action method: create_virtualenv 01:40:20 INFO - Creating virtualenv /builds/slave/test/build/venv 01:40:20 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 01:40:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:40:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:40:20 INFO - Using real prefix '/usr' 01:40:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:40:21 INFO - Installing distribute.............................................................................................................................................................................................done. 01:40:23 INFO - Installing pip.................done. 01:40:23 INFO - Return code: 0 01:40:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 01:40:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 01:40:23 INFO - Reading from file tmpfile_stdout 01:40:23 INFO - Output received: 01:40:23 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) 01:40:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:40: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')]} 01:40:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:40:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40: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')]} 01:40:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:40:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:23 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 0x11f61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1234a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1494310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1491400>, '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 0x14923b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1492840>, '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': '1f5564757af27664ca02d71f000001b0-1491986390.706528-1566323919', '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 01:40:23 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 01:40:23 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 01:40:23 INFO - Using env: {'DISPLAY': ':0', 01:40:23 INFO - 'HOME': '/home/cltbld', 01:40:23 INFO - 'LANG': 'en_US.UTF-8', 01:40:23 INFO - 'LANGUAGE': 'en_US:en', 01:40:23 INFO - 'LOGNAME': 'cltbld', 01:40:23 INFO - 'MAIL': '/var/mail/cltbld', 01:40:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:40:23 INFO - 'MOZ_NO_REMOTE': '1', 01:40:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:40:23 INFO - 'NO_EM_RESTART': '1', 01:40:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:40:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:40:23 INFO - 'PWD': '/builds/slave/test', 01:40:23 INFO - 'SHELL': '/bin/bash', 01:40:23 INFO - 'SHLVL': '1', 01:40:23 INFO - 'TERM': 'linux', 01:40:23 INFO - 'TMOUT': '86400', 01:40:23 INFO - 'USER': 'cltbld', 01:40:23 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1491986390.706528-1566323919', 01:40:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:40:23 INFO - '_': '/tools/buildbot/bin/python'} 01:40:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:40:23 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 01:40:23 INFO - Cleaning up... 01:40:23 INFO - Return code: 0 01:40:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:40:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:40:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:40:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:40:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:40:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11f61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1234a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1494310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1491400>, '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 0x14923b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1492840>, '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': '1f5564757af27664ca02d71f000001b0-1491986390.706528-1566323919', '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 01:40:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 01:40:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 01:40:24 INFO - Using env: {'DISPLAY': ':0', 01:40:24 INFO - 'HOME': '/home/cltbld', 01:40:24 INFO - 'LANG': 'en_US.UTF-8', 01:40:24 INFO - 'LANGUAGE': 'en_US:en', 01:40:24 INFO - 'LOGNAME': 'cltbld', 01:40:24 INFO - 'MAIL': '/var/mail/cltbld', 01:40:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:40:24 INFO - 'MOZ_NO_REMOTE': '1', 01:40:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:40:24 INFO - 'NO_EM_RESTART': '1', 01:40:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:40:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:40:24 INFO - 'PWD': '/builds/slave/test', 01:40:24 INFO - 'SHELL': '/bin/bash', 01:40:24 INFO - 'SHLVL': '1', 01:40:24 INFO - 'TERM': 'linux', 01:40:24 INFO - 'TMOUT': '86400', 01:40:24 INFO - 'USER': 'cltbld', 01:40:24 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1491986390.706528-1566323919', 01:40:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:40:24 INFO - '_': '/tools/buildbot/bin/python'} 01:40:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:40:24 INFO - Downloading/unpacking psutil>=3.1.1 01:40:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:40:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:40:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:40:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:40:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:40:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:40:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:40:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:40:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:40:26 INFO - Installing collected packages: psutil 01:40:26 INFO - Running setup.py install for psutil 01:40:26 INFO - building 'psutil._psutil_linux' extension 01:40:26 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 01:40:26 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 01:40:26 INFO - building 'psutil._psutil_posix' extension 01:40:26 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 01:40:26 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 01:40:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:40:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:40:26 INFO - Successfully installed psutil 01:40:26 INFO - Cleaning up... 01:40:26 INFO - Return code: 0 01:40:26 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:40: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')]} 01:40:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:40:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40: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')]} 01:40:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:40:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:26 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 0x11f61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1234a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1494310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1491400>, '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 0x14923b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1492840>, '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': '1f5564757af27664ca02d71f000001b0-1491986390.706528-1566323919', '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 01:40:26 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 01:40:26 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 01:40:26 INFO - Using env: {'DISPLAY': ':0', 01:40:26 INFO - 'HOME': '/home/cltbld', 01:40:26 INFO - 'LANG': 'en_US.UTF-8', 01:40:26 INFO - 'LANGUAGE': 'en_US:en', 01:40:26 INFO - 'LOGNAME': 'cltbld', 01:40:26 INFO - 'MAIL': '/var/mail/cltbld', 01:40:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:40:26 INFO - 'MOZ_NO_REMOTE': '1', 01:40:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:40:26 INFO - 'NO_EM_RESTART': '1', 01:40:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:40:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:40:26 INFO - 'PWD': '/builds/slave/test', 01:40:26 INFO - 'SHELL': '/bin/bash', 01:40:26 INFO - 'SHLVL': '1', 01:40:26 INFO - 'TERM': 'linux', 01:40:26 INFO - 'TMOUT': '86400', 01:40:26 INFO - 'USER': 'cltbld', 01:40:26 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1491986390.706528-1566323919', 01:40:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:40:26 INFO - '_': '/tools/buildbot/bin/python'} 01:40:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:40:27 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:40:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:40:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:40:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:40:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:40:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:40:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:40:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:40:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:40:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:40:29 INFO - Installing collected packages: mozsystemmonitor 01:40:29 INFO - Running setup.py install for mozsystemmonitor 01:40:29 INFO - Successfully installed mozsystemmonitor 01:40:29 INFO - Cleaning up... 01:40:29 INFO - Return code: 0 01:40:29 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 01:40: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')]} 01:40:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:40:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40: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')]} 01:40:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:40:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11f61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1234a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1494310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1491400>, '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 0x14923b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1492840>, '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': '1f5564757af27664ca02d71f000001b0-1491986390.706528-1566323919', '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 01:40:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 01:40:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 01:40:29 INFO - Using env: {'DISPLAY': ':0', 01:40:29 INFO - 'HOME': '/home/cltbld', 01:40:29 INFO - 'LANG': 'en_US.UTF-8', 01:40:29 INFO - 'LANGUAGE': 'en_US:en', 01:40:29 INFO - 'LOGNAME': 'cltbld', 01:40:29 INFO - 'MAIL': '/var/mail/cltbld', 01:40:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:40:29 INFO - 'MOZ_NO_REMOTE': '1', 01:40:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:40:29 INFO - 'NO_EM_RESTART': '1', 01:40:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:40:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:40:29 INFO - 'PWD': '/builds/slave/test', 01:40:29 INFO - 'SHELL': '/bin/bash', 01:40:29 INFO - 'SHLVL': '1', 01:40:29 INFO - 'TERM': 'linux', 01:40:29 INFO - 'TMOUT': '86400', 01:40:29 INFO - 'USER': 'cltbld', 01:40:29 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1491986390.706528-1566323919', 01:40:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:40:29 INFO - '_': '/tools/buildbot/bin/python'} 01:40:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:40:29 INFO - Downloading/unpacking jsonschema==2.5.1 01:40:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:40:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:40:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:40:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:40:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:40:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:40:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:40:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:40:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:40:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:40:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:40:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:40:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:40:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:40:31 INFO - Downloading functools32-3.2.3-2.tar.gz 01:40:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:40:32 INFO - warning: no files found matching '*.txt' 01:40:32 INFO - no previously-included directories found matching 'build' 01:40:32 INFO - no previously-included directories found matching 'dist' 01:40:32 INFO - no previously-included directories found matching '.git*' 01:40:32 INFO - Installing collected packages: jsonschema, functools32 01:40:32 INFO - Running setup.py install for functools32 01:40:32 INFO - warning: no files found matching '*.txt' 01:40:32 INFO - no previously-included directories found matching 'build' 01:40:32 INFO - no previously-included directories found matching 'dist' 01:40:32 INFO - no previously-included directories found matching '.git*' 01:40:32 INFO - Successfully installed jsonschema functools32 01:40:32 INFO - Cleaning up... 01:40:32 INFO - Return code: 0 01:40:32 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 01:40:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:40:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:40:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:40:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:40:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11f61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1234a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1494310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1491400>, '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 0x14923b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1492840>, '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': '1f5564757af27664ca02d71f000001b0-1491986390.706528-1566323919', '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 01:40:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 01:40:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 01:40:32 INFO - Using env: {'DISPLAY': ':0', 01:40:32 INFO - 'HOME': '/home/cltbld', 01:40:32 INFO - 'LANG': 'en_US.UTF-8', 01:40:32 INFO - 'LANGUAGE': 'en_US:en', 01:40:32 INFO - 'LOGNAME': 'cltbld', 01:40:32 INFO - 'MAIL': '/var/mail/cltbld', 01:40:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:40:32 INFO - 'MOZ_NO_REMOTE': '1', 01:40:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:40:32 INFO - 'NO_EM_RESTART': '1', 01:40:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:40:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:40:32 INFO - 'PWD': '/builds/slave/test', 01:40:32 INFO - 'SHELL': '/bin/bash', 01:40:32 INFO - 'SHLVL': '1', 01:40:32 INFO - 'TERM': 'linux', 01:40:32 INFO - 'TMOUT': '86400', 01:40:32 INFO - 'USER': 'cltbld', 01:40:32 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1491986390.706528-1566323919', 01:40:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:40:32 INFO - '_': '/tools/buildbot/bin/python'} 01:40:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:40:32 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 01:40:32 INFO - Cleaning up... 01:40:32 INFO - Return code: 0 01:40:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:40:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:40:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:40:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:40:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:40:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x11f61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1234a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1494310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1491400>, '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 0x14923b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1492840>, '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': '1f5564757af27664ca02d71f000001b0-1491986390.706528-1566323919', '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 01:40:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 01:40:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 01:40:32 INFO - Using env: {'DISPLAY': ':0', 01:40:32 INFO - 'HOME': '/home/cltbld', 01:40:32 INFO - 'LANG': 'en_US.UTF-8', 01:40:32 INFO - 'LANGUAGE': 'en_US:en', 01:40:32 INFO - 'LOGNAME': 'cltbld', 01:40:32 INFO - 'MAIL': '/var/mail/cltbld', 01:40:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:40:32 INFO - 'MOZ_NO_REMOTE': '1', 01:40:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:40:32 INFO - 'NO_EM_RESTART': '1', 01:40:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:40:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:40:32 INFO - 'PWD': '/builds/slave/test', 01:40:32 INFO - 'SHELL': '/bin/bash', 01:40:32 INFO - 'SHLVL': '1', 01:40:32 INFO - 'TERM': 'linux', 01:40:32 INFO - 'TMOUT': '86400', 01:40:32 INFO - 'USER': 'cltbld', 01:40:32 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1491986390.706528-1566323919', 01:40:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:40:32 INFO - '_': '/tools/buildbot/bin/python'} 01:40:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:40:32 INFO - Downloading/unpacking blobuploader==1.2.4 01:40:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:40:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:40:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:40:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:40:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:40:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:40:34 INFO - Downloading blobuploader-1.2.4.tar.gz 01:40:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:40:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:40:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:40:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:40:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:40:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:40:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:40:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:40:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:40:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:40:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:40:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:40:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:40:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:40:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:40:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:40:35 INFO - Downloading docopt-0.6.1.tar.gz 01:40:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:40:35 INFO - Installing collected packages: blobuploader, requests, docopt 01:40:35 INFO - Running setup.py install for blobuploader 01:40:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:40:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:40:35 INFO - Running setup.py install for requests 01:40:35 INFO - Running setup.py install for docopt 01:40:36 INFO - Successfully installed blobuploader requests docopt 01:40:36 INFO - Cleaning up... 01:40:36 INFO - Return code: 0 01:40:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:40:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:40:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:40:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:40:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:40:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11f61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1234a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1494310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1491400>, '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 0x14923b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1492840>, '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': '1f5564757af27664ca02d71f000001b0-1491986390.706528-1566323919', '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 01:40:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 01:40:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:40:36 INFO - Using env: {'DISPLAY': ':0', 01:40:36 INFO - 'HOME': '/home/cltbld', 01:40:36 INFO - 'LANG': 'en_US.UTF-8', 01:40:36 INFO - 'LANGUAGE': 'en_US:en', 01:40:36 INFO - 'LOGNAME': 'cltbld', 01:40:36 INFO - 'MAIL': '/var/mail/cltbld', 01:40:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:40:36 INFO - 'MOZ_NO_REMOTE': '1', 01:40:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:40:36 INFO - 'NO_EM_RESTART': '1', 01:40:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:40:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:40:36 INFO - 'PWD': '/builds/slave/test', 01:40:36 INFO - 'SHELL': '/bin/bash', 01:40:36 INFO - 'SHLVL': '1', 01:40:36 INFO - 'TERM': 'linux', 01:40:36 INFO - 'TMOUT': '86400', 01:40:36 INFO - 'USER': 'cltbld', 01:40:36 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1491986390.706528-1566323919', 01:40:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:40:36 INFO - '_': '/tools/buildbot/bin/python'} 01:40:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:40:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:40:36 INFO - Running setup.py (path:/tmp/pip-cK64Og-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:40:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:40:36 INFO - Running setup.py (path:/tmp/pip-CCXmyu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:40:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:40:36 INFO - Running setup.py (path:/tmp/pip-yNg2LL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:40:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:40:36 INFO - Running setup.py (path:/tmp/pip-uKef3v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:40:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:40:36 INFO - Running setup.py (path:/tmp/pip-2f6UoK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:40:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:40:36 INFO - Running setup.py (path:/tmp/pip-T8Jkhw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:40:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:40:36 INFO - Running setup.py (path:/tmp/pip-Ei6MI8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:40:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:40:36 INFO - Running setup.py (path:/tmp/pip-SzuS8h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:40:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:40:36 INFO - Running setup.py (path:/tmp/pip-Z5_os0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:40:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:40:37 INFO - Running setup.py (path:/tmp/pip-DXM87r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:40:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:40:37 INFO - Running setup.py (path:/tmp/pip-OxjY0O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:40:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:40:37 INFO - Running setup.py (path:/tmp/pip-0NTkqo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:40:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:40:37 INFO - Running setup.py (path:/tmp/pip-5JLr4b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:40:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:40:37 INFO - Running setup.py (path:/tmp/pip-OBXHJZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:40:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:40:37 INFO - Running setup.py (path:/tmp/pip-lw1XBE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:40:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:40:37 INFO - Running setup.py (path:/tmp/pip-jJZoHW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:40:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:40:37 INFO - Running setup.py (path:/tmp/pip-7_AB1h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:40:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:40:37 INFO - Running setup.py install for manifestparser 01:40:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:40:37 INFO - Running setup.py install for mozcrash 01:40:37 INFO - Running setup.py install for mozdebug 01:40:37 INFO - Running setup.py install for mozdevice 01:40:38 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:40:38 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:40:38 INFO - Running setup.py install for mozfile 01:40:38 INFO - Running setup.py install for mozhttpd 01:40:38 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:40:38 INFO - Running setup.py install for mozinfo 01:40:38 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:40:38 INFO - Running setup.py install for mozInstall 01:40:38 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:40:38 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:40:38 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:40:38 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:40:38 INFO - Running setup.py install for mozleak 01:40:38 INFO - Running setup.py install for mozlog 01:40:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:40:38 INFO - Running setup.py install for moznetwork 01:40:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:40:38 INFO - Running setup.py install for mozprocess 01:40:38 INFO - Running setup.py install for mozprofile 01:40:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:40:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:40:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:40:38 INFO - Running setup.py install for mozrunner 01:40:39 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:40:39 INFO - Running setup.py install for mozscreenshot 01:40:39 INFO - Running setup.py install for moztest 01:40:39 INFO - Running setup.py install for mozversion 01:40:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:40:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:40:39 INFO - Cleaning up... 01:40:39 INFO - Return code: 0 01:40:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:40:39 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')]} 01:40:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:40:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:39 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')]} 01:40:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:40:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:39 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 0x11f61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1234a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1494310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1491400>, '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 0x14923b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1492840>, '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': '1f5564757af27664ca02d71f000001b0-1491986390.706528-1566323919', '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 01:40:39 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 01:40:39 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 01:40:39 INFO - Using env: {'DISPLAY': ':0', 01:40:39 INFO - 'HOME': '/home/cltbld', 01:40:39 INFO - 'LANG': 'en_US.UTF-8', 01:40:39 INFO - 'LANGUAGE': 'en_US:en', 01:40:39 INFO - 'LOGNAME': 'cltbld', 01:40:39 INFO - 'MAIL': '/var/mail/cltbld', 01:40:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:40:39 INFO - 'MOZ_NO_REMOTE': '1', 01:40:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:40:39 INFO - 'NO_EM_RESTART': '1', 01:40:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:40:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:40:39 INFO - 'PWD': '/builds/slave/test', 01:40:39 INFO - 'SHELL': '/bin/bash', 01:40:39 INFO - 'SHLVL': '1', 01:40:39 INFO - 'TERM': 'linux', 01:40:39 INFO - 'TMOUT': '86400', 01:40:39 INFO - 'USER': 'cltbld', 01:40:39 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1491986390.706528-1566323919', 01:40:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:40:39 INFO - '_': '/tools/buildbot/bin/python'} 01:40:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:40:39 INFO - Running setup.py (path:/tmp/pip-jghQ3N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:40:39 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)) 01:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:40:39 INFO - Running setup.py (path:/tmp/pip-0aXKZw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:40:39 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)) 01:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:40:39 INFO - Running setup.py (path:/tmp/pip-HwkCtn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:40:39 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)) 01:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:40:39 INFO - Running setup.py (path:/tmp/pip-yTWSr8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:40:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:40:39 INFO - Running setup.py (path:/tmp/pip-wzUePT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:40:39 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)) 01:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:40:39 INFO - Running setup.py (path:/tmp/pip-fM8KMS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:40:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 01:40:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:40:40 INFO - Running setup.py (path:/tmp/pip-nJ6fSe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:40:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 01:40:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:40:40 INFO - Running setup.py (path:/tmp/pip-h3miZ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:40:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 01:40:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:40:40 INFO - Running setup.py (path:/tmp/pip-1p8BIX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:40:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 01:40:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:40:40 INFO - Running setup.py (path:/tmp/pip-wglf1J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:40:40 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)) 01:40:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:40:40 INFO - Running setup.py (path:/tmp/pip-Ln7kAC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:40:40 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)) 01:40:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:40:40 INFO - Running setup.py (path:/tmp/pip-ITZLba-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:40:40 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)) 01:40:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:40:40 INFO - Running setup.py (path:/tmp/pip-0pbBev-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:40:40 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)) 01:40:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:40:40 INFO - Running setup.py (path:/tmp/pip-FAX3Zh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:40:40 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)) 01:40:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:40:40 INFO - Running setup.py (path:/tmp/pip-MSgsUE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:40:40 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)) 01:40:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:40:40 INFO - Running setup.py (path:/tmp/pip-OH5k7q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:40:40 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)) 01:40:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:40:40 INFO - Running setup.py (path:/tmp/pip-6J6O4E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:40:40 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)) 01:40:40 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)) 01:40:40 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)) 01:40:40 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)) 01:40:40 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:40:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:40:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:40:40 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 01:40:40 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 01:40:40 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 01:40:40 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 01:40:40 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 01:40:40 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 01:40:42 INFO - Downloading blessings-1.6.tar.gz 01:40:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:40:42 INFO - Installing collected packages: blessings 01:40:42 INFO - Running setup.py install for blessings 01:40:43 INFO - Successfully installed blessings 01:40:43 INFO - Cleaning up... 01:40:43 INFO - Return code: 0 01:40:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:40:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:40:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:40:43 INFO - Reading from file tmpfile_stdout 01:40:43 INFO - Current package versions: 01:40:43 INFO - argparse == 1.2.1 01:40:43 INFO - blessings == 1.6 01:40:43 INFO - blobuploader == 1.2.4 01:40:43 INFO - docopt == 0.6.1 01:40:43 INFO - functools32 == 3.2.3-2 01:40:43 INFO - jsonschema == 2.5.1 01:40:43 INFO - manifestparser == 1.1 01:40:43 INFO - mozInstall == 1.12 01:40:43 INFO - mozcrash == 1.0 01:40:43 INFO - mozdebug == 0.1 01:40:43 INFO - mozdevice == 0.49 01:40:43 INFO - mozfile == 1.2 01:40:43 INFO - mozhttpd == 0.7 01:40:43 INFO - mozinfo == 0.9 01:40:43 INFO - mozleak == 0.1 01:40:43 INFO - mozlog == 3.4 01:40:43 INFO - moznetwork == 0.27 01:40:43 INFO - mozprocess == 0.25 01:40:43 INFO - mozprofile == 0.28 01:40:43 INFO - mozrunner == 6.13 01:40:43 INFO - mozscreenshot == 0.1 01:40:43 INFO - mozsystemmonitor == 0.3 01:40:43 INFO - moztest == 0.8 01:40:43 INFO - mozversion == 1.4 01:40:43 INFO - psutil == 3.1.1 01:40:43 INFO - requests == 1.2.3 01:40:43 INFO - wsgiref == 0.1.2 01:40:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:40:43 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')]} 01:40:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:40:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:43 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')]} 01:40:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:40:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:43 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 0x11f61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1234a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1494310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1491400>, '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 0x14923b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1492840>, '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': '1f5564757af27664ca02d71f000001b0-1491986390.706528-1566323919', '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 01:40:43 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 01:40:43 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 01:40:43 INFO - Using env: {'DISPLAY': ':0', 01:40:43 INFO - 'HOME': '/home/cltbld', 01:40:43 INFO - 'LANG': 'en_US.UTF-8', 01:40:43 INFO - 'LANGUAGE': 'en_US:en', 01:40:43 INFO - 'LOGNAME': 'cltbld', 01:40:43 INFO - 'MAIL': '/var/mail/cltbld', 01:40:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:40:43 INFO - 'MOZ_NO_REMOTE': '1', 01:40:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:40:43 INFO - 'NO_EM_RESTART': '1', 01:40:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:40:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:40:43 INFO - 'PWD': '/builds/slave/test', 01:40:43 INFO - 'SHELL': '/bin/bash', 01:40:43 INFO - 'SHLVL': '1', 01:40:43 INFO - 'TERM': 'linux', 01:40:43 INFO - 'TMOUT': '86400', 01:40:43 INFO - 'USER': 'cltbld', 01:40:43 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1491986390.706528-1566323919', 01:40:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:40:43 INFO - '_': '/tools/buildbot/bin/python'} 01:40:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:40:43 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)) 01:40:43 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)) 01:40:43 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)) 01:40:43 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)) 01:40:43 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)) 01:40:43 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)) 01:40:43 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)) 01:40:43 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)) 01:40:43 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)) 01:40:43 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)) 01:40:43 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)) 01:40:43 INFO - Cleaning up... 01:40:43 INFO - Return code: 0 01:40:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:40:43 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')]} 01:40:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:40:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:43 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')]} 01:40:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:40:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:43 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 0x11f61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1234a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1494310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1491400>, '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 0x14923b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1492840>, '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': '1f5564757af27664ca02d71f000001b0-1491986390.706528-1566323919', '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 01:40:43 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 01:40:43 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 01:40:43 INFO - Using env: {'DISPLAY': ':0', 01:40:43 INFO - 'HOME': '/home/cltbld', 01:40:43 INFO - 'LANG': 'en_US.UTF-8', 01:40:43 INFO - 'LANGUAGE': 'en_US:en', 01:40:43 INFO - 'LOGNAME': 'cltbld', 01:40:43 INFO - 'MAIL': '/var/mail/cltbld', 01:40:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:40:43 INFO - 'MOZ_NO_REMOTE': '1', 01:40:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:40:43 INFO - 'NO_EM_RESTART': '1', 01:40:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:40:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:40:43 INFO - 'PWD': '/builds/slave/test', 01:40:43 INFO - 'SHELL': '/bin/bash', 01:40:43 INFO - 'SHLVL': '1', 01:40:43 INFO - 'TERM': 'linux', 01:40:43 INFO - 'TMOUT': '86400', 01:40:43 INFO - 'USER': 'cltbld', 01:40:43 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1491986390.706528-1566323919', 01:40:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:40:43 INFO - '_': '/tools/buildbot/bin/python'} 01:40:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:40:43 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 01:40:43 INFO - Cleaning up... 01:40:43 INFO - Return code: 0 01:40:43 INFO - Running post-action listener: _resource_record_post_action 01:40:43 INFO - Running post-action listener: _start_resource_monitoring 01:40:43 INFO - Starting resource monitoring. 01:40:43 INFO - [mozharness: 2017-04-12 08:40:43.823143Z] Finished create-virtualenv step (success) 01:40:43 INFO - [mozharness: 2017-04-12 08:40:43.823619Z] Running install step. 01:40:43 INFO - Running pre-action listener: _resource_record_pre_action 01:40:43 INFO - Running main action method: install 01:40:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:40:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:40:44 INFO - Reading from file tmpfile_stdout 01:40:44 INFO - Detecting whether we're running mozinstall >=1.0... 01:40:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:40:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:40:44 INFO - Reading from file tmpfile_stdout 01:40:44 INFO - Output received: 01:40:44 INFO - Usage: mozinstall [options] installer 01:40:44 INFO - Options: 01:40:44 INFO - -h, --help show this help message and exit 01:40:44 INFO - -d DEST, --destination=DEST 01:40:44 INFO - Directory to install application into. [default: 01:40:44 INFO - "/builds/slave/test"] 01:40:44 INFO - --app=APP Application being installed. [default: firefox] 01:40:44 INFO - mkdir: /builds/slave/test/build/application 01:40:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 01:40:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 01:41:00 INFO - Reading from file tmpfile_stdout 01:41:00 INFO - Output received: 01:41:00 INFO - /builds/slave/test/build/application/firefox/firefox 01:41:00 INFO - Running post-action listener: _resource_record_post_action 01:41:00 INFO - [mozharness: 2017-04-12 08:41:00.731489Z] Finished install step (success) 01:41:00 INFO - [mozharness: 2017-04-12 08:41:00.731761Z] Running run-tests step. 01:41:00 INFO - Running pre-action listener: _resource_record_pre_action 01:41:00 INFO - Running main action method: run_tests 01:41:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:41:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:41:00 INFO - Python 2.7.3 01:41:00 INFO - Return code: 0 01:41:00 INFO - grabbing minidump binary from tooltool 01:41:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:41:00 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 0x1491400>, '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 0x14923b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1492840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:41:00 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 01:41:00 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 01:41:00 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 01:41:00 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:41:00 INFO - Return code: 0 01:41:00 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 01:41:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:41:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:41:00 INFO - ENV: RUST_BACKTRACE is now 1 01:41:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 01:41:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:41:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KeVv12r4Q1ed-jtV1j3DjA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-027', '--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 01:41:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/KeVv12r4Q1ed-jtV1j3DjA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-027 --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 01:41:00 INFO - Using env: {'DISPLAY': ':0', 01:41:00 INFO - 'HOME': '/home/cltbld', 01:41:00 INFO - 'LANG': 'en_US.UTF-8', 01:41:00 INFO - 'LANGUAGE': 'en_US:en', 01:41:00 INFO - 'LOGNAME': 'cltbld', 01:41:00 INFO - 'MAIL': '/var/mail/cltbld', 01:41:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:41:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 01:41:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:41:00 INFO - 'MOZ_NO_REMOTE': '1', 01:41:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:41:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:41:00 INFO - 'NO_EM_RESTART': '1', 01:41:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:41:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:41:00 INFO - 'PWD': '/builds/slave/test', 01:41:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:41:00 INFO - 'RUST_BACKTRACE': '1', 01:41:00 INFO - 'SHELL': '/bin/bash', 01:41:00 INFO - 'SHLVL': '1', 01:41:00 INFO - 'TERM': 'linux', 01:41:00 INFO - 'TMOUT': '86400', 01:41:00 INFO - 'USER': 'cltbld', 01:41:00 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1491986390.706528-1566323919', 01:41:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:41:00 INFO - '_': '/tools/buildbot/bin/python'} 01:41:00 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KeVv12r4Q1ed-jtV1j3DjA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-027', '--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 01:41:01 INFO - mozversion application_buildid: 20170412074707 01:41:01 INFO - mozversion application_changeset: d76bbedb3cf4550b4805b18d51afa7f115047623 01:41:01 INFO - mozversion application_display_name: FirefoxDeveloperEdition 01:41:01 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:41:01 INFO - mozversion application_name: Firefox 01:41:01 INFO - mozversion application_remotingname: firefox-dev 01:41:01 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 01:41:01 INFO - mozversion application_vendor: Mozilla 01:41:01 INFO - mozversion application_version: 54.0a2 01:41:01 INFO - mozversion platform_buildid: 20170412074707 01:41:01 INFO - mozversion platform_changeset: d76bbedb3cf4550b4805b18d51afa7f115047623 01:41:01 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 01:41:01 INFO - mozversion platform_version: 54.0a2 01:41:01 INFO - using testdate: 1491986461 01:41:01 INFO - actual date: 1491986461 01:41:01 INFO - starting webserver on 'localhost:35239' 01:41:01 INFO - SUITE-START | Running 2 tests 01:41:01 INFO - TEST-START | dromaeo_css 01:41:01 INFO - operating with platform_type : linux_ 01:41:01 INFO - Initialising browser for dromaeo_css test... 01:41:01 INFO - TEST-INFO | started process 18036 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptMI0fV/profile http://localhost:35239/getInfo.html) 01:41:05 INFO - PROCESS | 18036 | __metrics Screen width/height:1600/1200 01:41:05 INFO - PROCESS | 18036 | colorDepth:24 01:41:05 INFO - PROCESS | 18036 | Browser inner width/height: 1024/697 01:41:05 INFO - PROCESS | 18036 | __metrics 01:41:05 INFO - PROCESS | 18036 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 01:41:05 INFO - PROCESS | 18036 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:41:06 INFO - TEST-INFO | 18036: exit 0 01:41:06 INFO - Browser initialized. 01:41:06 INFO - Running cycle 1/1 for dromaeo_css test... 01:41:06 INFO - TEST-INFO | started process 18130 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptMI0fV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:41:18 INFO - PROCESS | 18130 | RSS: Main: 139505664 01:41:18 INFO - PROCESS | 18130 | 01:44:19 INFO - PROCESS | 18130 | Cycle 1(1): loaded http://localhost:35239/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35239/tests/dromaeo/cssquery-ext.html) 01:44:21 INFO - PROCESS | 18130 | RSS: Main: 274759680 01:44:21 INFO - PROCESS | 18130 | 01:46:08 INFO - PROCESS | 18130 | ************************* 01:46:08 INFO - PROCESS | 18130 | A coding exception was thrown and uncaught in a Task. 01:46:08 INFO - PROCESS | 18130 | 01:46:08 ERROR - PROCESS | 18130 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:46:08 INFO - PROCESS | 18130 | Full stack: 01:46:08 INFO - PROCESS | 18130 | ************************* 01:46:08 INFO - PROCESS | 18130 | ************************* 01:46:08 INFO - PROCESS | 18130 | A coding exception was thrown and uncaught in a Task. 01:46:08 INFO - PROCESS | 18130 | 01:46:08 ERROR - PROCESS | 18130 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:46:08 INFO - PROCESS | 18130 | Full stack: 01:46:08 INFO - PROCESS | 18130 | ************************* 01:46:08 INFO - PROCESS | 18130 | ************************* 01:46:08 INFO - PROCESS | 18130 | A coding exception was thrown and uncaught in a Task. 01:46:08 INFO - PROCESS | 18130 | 01:46:08 ERROR - PROCESS | 18130 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:46:08 INFO - PROCESS | 18130 | Full stack: 01:46:08 INFO - PROCESS | 18130 | ************************* 01:46:08 INFO - PROCESS | 18130 | ************************* 01:46:08 INFO - PROCESS | 18130 | A coding exception was thrown and uncaught in a Task. 01:46:08 INFO - PROCESS | 18130 | 01:46:08 ERROR - PROCESS | 18130 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:46:08 INFO - PROCESS | 18130 | Full stack: 01:46:08 INFO - PROCESS | 18130 | ************************* 01:47:23 INFO - PROCESS | 18130 | Cycle 1(1): loaded http://localhost:35239/tests/dromaeo/cssquery-ext.html (next: http://localhost:35239/tests/dromaeo/cssquery-jquery.html) 01:47:24 INFO - PROCESS | 18130 | RSS: Main: 214495232 01:47:24 INFO - PROCESS | 18130 | 01:50:26 INFO - PROCESS | 18130 | Cycle 1(1): loaded http://localhost:35239/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35239/tests/dromaeo/cssquery-mootools.html) 01:50:26 INFO - PROCESS | 18130 | RSS: Main: 218157056 01:50:26 INFO - PROCESS | 18130 | 01:53:28 INFO - PROCESS | 18130 | Cycle 1(1): loaded http://localhost:35239/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35239/tests/dromaeo/cssquery-prototype.html) 01:53:29 INFO - PROCESS | 18130 | RSS: Main: 170553344 01:53:29 INFO - PROCESS | 18130 | 01:56:32 INFO - PROCESS | 18130 | Cycle 1(1): loaded http://localhost:35239/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35239/tests/dromaeo/cssquery-yui.html) 01:56:32 INFO - PROCESS | 18130 | RSS: Main: 175984640 01:56:32 INFO - PROCESS | 18130 | 01:59:35 INFO - PROCESS | 18130 | Cycle 1(1): loaded http://localhost:35239/tests/dromaeo/cssquery-yui.html (next: http://localhost:35239/tests/dromaeo/cssquery-dojo.html) 01:59:35 INFO - PROCESS | 18130 | RSS: Main: 269692928 01:59:35 INFO - PROCESS | 18130 | 01:59:35 INFO - PROCESS | 18130 | __start_tp_report 01:59:35 INFO - PROCESS | 18130 | _x_x_mozilla_page_load 01:59:35 INFO - PROCESS | 18130 | _x_x_mozilla_page_load_details 01:59:35 INFO - PROCESS | 18130 | |i|pagename|runs| 01:59:35 INFO - PROCESS | 18130 | |0;dojo.html;3380.104272367823;3399.1841957930087;3411.266577685797;3417.4532074868025;3444.3627928833166;5652.773889044433;5748.25272714547;5753.884922301546;5756.971215143929;5762.049261871786;5754.367019627837;5821.359650438459;5826.679532625704;5832.037713777222;5837.795677151301;5119.539241468267;5155.664881782672;5163.302954101196;5189.844304670847;5223.425423203445;5003.499650034985;5018.723970181639;5240.528352448279;5259.526642602165;5281.815136470224;5088.160453525183;5116.948830511703;5171.482851714817;5189.481051894818;5193.610479214055;5171;5204.843854684365;5208.479152084799;5229.7385130743505;5242.947570524322;4927.6550641455115;5063.620228482841;5130.07658621448;5181.093308670967;5187.19598462239;5569.0254205514275;5630.042892708249;5690.687012214326;5707.543396528268;5712.771489140429;6104.816855494342;6134.539909506805;6143.815685529441;6159.938400615982;6178;6213.2233470816145;6226.470749986257;6227.813165605039;6232.220972378453;6243.406876346785;5439.782408703687;5448.264484294609;5696.800611978644;5701.686407247558;5703.230063941356;6243.687815609293;6251.9687401563615;6258.15514905491;6261.7182226799905;6317.842053948596;6027.608205466719;6070.3019152798;6070.696465176724;6071.969640151771;6081.665508397081;5925.911111333334;5961.5230781537375;6105.877882442415;6123.969380153071;6131.509479241739;6139.185504090456;6140.324564297924;6148.938510614926;6151.815445536641;6162.537809664203;5882.470577647947;5910.734016969247;5912.17229587858;6074.210352654215;6159.23009623797;4700.341952126705;4718.363020992191;4754.7147171169845;4777.570018698334;4780.713157210578;4123.60825721562;4128.298189307825;4216.114615930689;4230.277355926764;4235.5552666969525;5878.382769809194;5966.31387390447;5987.520998320124;6043.123747056652;6052.54605904559;6191.854969503405;6222.253329600477;6229.470495007885;6232.937670623236;6235.906461403082;5745.2818397700285;5787.421257874179;6001.669908155006;6007.759689612454;6035.064564992434;5176.301199338041;5200.219967004904;5226.973865130726;5238.685678859204;5289.68261904287;6446.935530644727;6474;6480.416762491398;6480.61116333031;6481.449076828428;6117.051856962178;6130.417610327012;6176.66028368444;6180.47465965398;6182.876342473215;6075.787347442818;6117.021276595723;6383.202099737533;6426.518011149276;6462.192225971754;6059.787907423219;6062.545309101747;6094.329623741473;6102.511799055976;6121.387861213843;6183.134361189527;6198.024948024883;6234.376562343729;6235.438810507076;6237.625742455467;5726.971365143314;5754.56840736938;5864.560157988082;5983.981067630534;6086.391360864055;5893.322267939242;5896;5922.1412895130825;5935.9703201485445;5972.8805423892145;5848.239728835224;5876.030454974882;5883.441073098049;5890.705464726832;5892.145638882253;5794.681292528783;5827.155062515996;5835.56233282497;6015.187949626745;6070.423309785563;5846.473817356288;5899.233099697012;5902.350741418355;5922.466977971831;5939.020061689773 01:59:35 INFO - PROCESS | 18130 | |1;ext.html;773.7794728502374;840.071720748573;855.3584811391454;912.9497877616728;928.1275600935116;32496.18759531013;44214;44699.55300446995;45126.999999999956;45237.32144017834;46408.767956160256;46643;46656;46717;46746.53253467464;7723.11184213817;7863.842723145563;8822.647094116228;8939.776505587348;8951.149640784115;10577.153827693766;10837.566497340096;11129.332240065582;11142.944285278561;11145.777084458306;10830.40432776197;10877.564897404094;10910;10917.672469825919;10920.453977301142;10825;10830.241883068189;10868.945655271673;10874.238803283773;10879.891201088007;10546.367217966947;10591.84112238317;10618.309809862334;10620.840687389695;10623;43660.78169609163;43953.78023109896;44079.33880991788;44094.559054409365;44116.77941610303;4224.112936283384;4256.212600668855;4308.547602501723;4394.121175764828;4396.582324679151;30206;30578;30595.541066884016;31039;32012.839935800173;4245.278302688519;4341.978290108561;4350.934735978963;4354.564543545652;4407.625351845097;242320;242666;242720;242783;242830;54470.45529544733;54661;54739.72630136824;54843.45156548383;54866.451335486934;56869;57002.42997569971;57064.71467642718;57169;57175.714121429126;16451.25969331359;16789.496315110566;16790.83209167917;16805.075720835226;16813.579660508585;12889.871101289054;13110.803337949937;14723.779143312859;14984.175870327237;15002.174880381684;13640.181589104686;13843.515476958259;13847.515336963157;13880.93059534696;13945.860541394659;5509.118541033496;5640.407757185519;5677.205191273225;5691.373948865621;5713.4000929902595;1038.5378506564657;1042.516363925756;1050.821360368739;1062.0600768319937;1080.9621663241749;28452.715472845008;28542.857285713853;28570.002645093053;28841;28899;1109.3399427340719;1119.9664010079712;1126.3129491010477;1132.9830052549219;1141.1384404774342;14723.484678036217;14749.188794616184;15163.545093646988;15174.786017118604;15282.770758438632;297230;297613;300465;300748;301074;57884;58014.4198558009;58031.129533057036;58034.7098264506;58106.12840807391;1508.2986411318789;1571.2065406969407;1574.1027614259763;1581.7390130628548;1591.554364777864;26252.73747262503;27329.86335068312;27515.17454476369;27890.581641274992;28175;26118.86940565285;26135.346616333984;26148;26174.738252617994;26188.476230474902;289919;292354;292776;293351;293389;30187.09438716754;30918;35815.82092089523;36111.63888361083;36428.81785591055;44897.32654010258;45039.324410134526;45257.094858103315;45309.32036019397;45742;34897.30205395928;35228.82385588055;35232.64767352294;35274;35751.64248357587;34132.317353653285;34394.312113757085;34539.4819077709;34645.133871652404;35525;39239.60760392359;39606.80196598999;39743.2051358977;40214;40580;32010.35979280448;32305;32537.837310813295;32565.511517326784;33167;26007.609885852107;26241.73758262393;26505.337366565218;26771.19686409411;27162.32094197661 01:59:35 INFO - PROCESS | 18130 | |2;jquery.html;450.73857162845553;467.2990514228657;471.2460063897764;479.4127194187119;487.4272729542787;19445.027748612567;21282.57434851302;21720.2397916073;21909.780902190992;21942.89028554859;22035.00842462089;22273.55452890945;22475.438114047156;22782.88608556959;22835.200767973125;5003.774830132635;6568.835779105537;6769.796906092801;6856.760013399532;6893.034975103489;4167.874963751093;4312.935305970413;6399.072134540489;7336.413086953057;7652.732154374598;7594.240575942416;7797.844043119134;7867.8033049173655;7879.4878332908465;7882.80292992674;7737.264959828835;7756.573388463632;7859.528428294292;7869.96065019677;7874.3700503959835;7495.175530691619;7547.245275472464;7579.848403031963;7590.316871481538;7609.6575654095695;21037.47406314839;21106.577868442546;21148.682769758467;24288.149914753074;24753.5049299013;2890.1762997545597;3010.127063151683;3048.695130486956;3068.171593669719;3089.6601373848857;16168.434104806276;16964.0669763163;17179;17214.741778873326;17414.651706965917;2257.8532395394245;2291.346966114666;2355.611324131516;2387.6179811230118;2393.557191919483;85101;102263.73844777944;104879.4756026215;106149;107678.46160769224;18716.625667486573;18916.24335026611;19157.13792879324;19269.4219173425;19644;34828;34828.477572836426;34883.30233395318;34998.47502287468;35096.29807403889;10964;11303.321800691985;11351.318920864775;11359.261647992855;11652.53389864413;9439.52802359893;9440.527973601293;9486.430814151036;9657.758556036155;9691.51542422876;10001.449920254456;10201.591936322613;10220.540075696612;10236.385816851012;10500.684979450629;3238.578984732017;3380.242274017497;3894.3184942634925;3905.3556163232747;3944.8027598619956;985.605757696927;1000.7798284377425;1011.7925825205815;1022.0392830739081;1044.9268551201494;21285.574288514144;21376.94560794934;21393.57212855734;22231.44421389478;22428.32715018552;1092.3718861654581;1103.0293341859112;1114.2256131988352;1117.474786850179;1141.8230174322994;11176.776464470666;11332;11430.25703329281;11492.310461372177;11879.465424055938;644198;660557.2631631538;671722;673823;682285;113952;117519;118925;119250;122128.38935805263;1273.1724379153625;1307.7972914198315;1351.7769568021167;1358.4566173530666;1361.1424802374638;19128.808711913258;19226.230950761812;19440.31958881489;19534;19547;52760.47239527556;53034;53094;53543.73228133834;53694;69100.65449672719;69341.3065869355;70929.64535177498;70997.29002709906;71044.93432598619;12230.755384892074;12830.29433381173;12849.421776020288;13017.674558136123;13018;10846.67459976202;13921.025528212927;14013.229272390117;14456.421743130157;14696.55910322692;12345.382730863601;12615.053870959531;12620.684482888002;12720.109592328448;12797.656246094026;10039.849402258826;10552.736181595521;10799.838002429813;10964.287321324407;11399.352207293918;12159.939200303941;12244.938775306422;12794.936025319814;12866.228026318451;12935;12190.268583884996;12499.375031248219;12531.812022819848;12768.425420855898;12921.741565168832;11035;11316.37759923212;11339.886601133883;11840.28958262507;12576.559820406243 01:59:35 INFO - PROCESS | 18130 | |3;mootools.html;1498.101139316411;1520.0803513874107;1576.9986058852633;1579.9210039498025;1584.944526941557;10903.945480272598;11501.309921404722;11599.942000290008;11609;11621.767564648713;11224.270422422536;11489.482973266202;11492.195546311761;11519.020883224935;11527.769444611104;2439.524292762912;2447.755224477547;2473.88867500962;2478.2069737683946;2479.4917042006437;2394.389430695179;2413.903443862252;2467.0748469324003;2469.8271121021535;2471.1351027140536;2419.5427659142;2429.3197904586655;2460.520198561281;2507.736687647798;2515.8238923275376;2412.843165194257;2426.5389575980594;2443.5357282116343;2449.2529778417575;2454.496828150234;2388.319328991229;2400.8079353651665;2406.350285422926;2414.8792560372085;2418.238254949703;11446.198766086378;11603.477843497063;11605;11611.883881161164;11616.883831161666;5724.4561766632105;5846.883062338772;5859.414058594107;5864.648121112704;5874.7943821966455;3401.1327111586634;3458.706009989154;3499.055255081114;3607.3867442534834;3635.472856435802;1310.6330227536305;1342.6441992871896;1369.1516452788755;1379.4137491566144;1440.387835170059;14555.417783288656;15304.234788260543;15352.462663806713;17558.473245802645;17757.378491752726;4735.058625963338;4752.1683705351425;4865.367502224688;4886.169351210303;4906.484819093944;11098.667039988813;11408.144389170679;13415.865841341461;13714.588562343146;13755.931220344035;5774.2782152230975;5954.523638108941;6028.969855150696;6032.155498230251;6059.697015149225;5495.230667706524;5551.417101204315;5551.9722401387735;5577.832665020056;5590.8043218487155;3956.1296514766705;4008.2183974124764;4025.8993525162105;4068.105016896278;4087.223427548816;1005.1958433253368;1013.2046343620223;1022.4376592874038;1038.8389799581078;1040.4693606260862;906.3655441191061;917.8852643419573;918.3984490158956;934.3807626326882;941.1153515695362;6457.321981192001;6644.202695676549;6668.766593169215;6773.830654233683;6841.281665425158;1035.2597892506894;1035.6116456328878;1039.9792004160026;1048.2505008918508;1066.7866426714688;4228.217779710763;4233.830646774156;4244.3209086546;4269.786510674454;4270;14417.85582144186;14981.625459363604;15019.399224031135;15054.096754194783;15076.170810605303;14509.492167774077;14860;14931.92534037323;15021.098734075771;15051.172185529682;10286.537105830106;10432.895671043192;10436.843447348296;10838.945805270923;10969.561217551209;6573.474122070318;6826.385625293748;6837.658117094026;6843.486738494732;6936.34104760047;13173.341332933507;13173.934130329288;13469.39387727535;13583.79624305656;13619.455221791017;13051.738965220833;13143.474261029452;13391.129576577881;13557.18656880551;13633.113847599874;8690.130986901511;8919.777005574913;10476.476176191007;10900.890991089987;10906.836397454203;10940;11163.665090047312;11385.430728463709;11471.369074700639;11517.25137866036;8795.47227166372;9125.041870603756;9256.814863702553;9551.570179341807;9573.425594464354;9073.410228335137;9159.587818548314;9171.862422063541;9203.815923681623;9278.907210927804;8307.127751585997;8403.831923361377;8408.2432581068;8409.075001749925;8419.663213471393;6338.524610654127;6349.714262858275;6365.490760739222;6421.293657697556;6485.643289619116;5961.403859614177;6049.818505444844;6054.061620448838;6065.272167339771;6156.722947467285 01:59:35 INFO - PROCESS | 18130 | |4;prototype.html;426.4179395125653;429.0603578163822;432.06458018390185;432.1033854751392;436.4827679200148;5071.84784456466;5187.792488300468;5197.168453047513;5201.609879259061;5211.114110601197;4974.850754477371;5050.292958985736;5058.924116138261;5059.3169922060515;5087.262346959688;4165.1669666066755;4279.935800962988;4285.907093691105;4286.699931004831;4306.913861722764;4283.614474697277;4315.374270730742;4319.3952846601505;4334.436523251989;4366.694331396803;4307;4328.63206627437;4373.934390984122;4382.458174258938;4382.824687012516;4117.464729585165;4248.830046798124;4254.893627659302;4352.651787856979;4369.322754972968;3976.16500534888;3987.282289187945;3995.380715989026;4008.3386241270155;4050.5544390117066;5024.397072351341;5030.698158110525;5031.572316353115;5052.686301561584;5085.466026067284;4813.927791083137;4955.578775804061;5001.599872010231;5016.548510634024;5047.141985862412;5367;5381.847261095554;5395.111710901153;5411.485908838694;5434.804343044524;4480.820767169342;4482.8431004915;4542.227821270392;4545.477270113913;4552.132156741265;5428.891422171534;5556.555475561945;5577.60559860039;5669.291338582677;5683.3464151622675;4631.235846085426;4650.441946966385;4659.347691323217;4679.532046795293;4683.7892294846815;4697.31888876111;4702.741349225826;4729.408823897013;4733.502982186889;4743.573078422958;4803.759812009385;4935.506449355035;4952.975235123873;4988.678000329915;5026.648134630614;5331.866703332447;5367.78528858849;5434.728263586805;5446.673199608036;5448.700321482356;1621.3433559408459;1635.2069246415717;1650.529599064261;1650.951645704962;1655.78299949537;1166.2827361172988;1255.7174635707077;1260.2438536877983;1290.2967882504058;1297.1179597873347;5312.846538857302;5324.201369794562;5332.68003919766;5383.81156659515;5407.675539467645;5290.418054014055;5628.746706398222;5631.887362252732;5635.971820140873;5636.408177141423;5334.386150786087;5378.462153784591;5406;5406.91889621656;5440.836774896759;2914.79596428248;2918.8102773319665;2920.7517361024125;2924.9122526324245;2935.9448386129366;5506.256655351556;5740.913886291709;5745;5780.092569452071;5804.303483581997;5422.566194704415;5625.549956003591;5640.830775076754;5647.188148000084;5673.893590749764;5639.464250896158;5713.857153571111;5755.9424405756245;5787.971060144672;5790.7973220937065;5289.43462420718;5437.483439073281;5500.22996780443;5529.677535435047;5561.555075594024;5075.416327122428;5374.381946076252;5395.78416863321;5476.67139971603;5514.255575497256;5731.656100633975;5735.8566035849435;5736.139579063007;5763.8559036024435;5770.192173095854;4405.449318835145;4589.678722489527;4688.444797585256;4730.763461826826;4765.166095933268;5550.916736248879;5551.694656793915;5560.304961879765;5566.248556444828;5603.915941260802;5289.841304760864;5429.864253393696;5451.155070964007;5463.808766693147;5469.562435005269;5393.622446428869;5399.83800485986;5403.055334842599;5412.864678383072;5500.97446404484;5366.409694933476;5384.250118711356;5384.353877534721;5396.64921780083;5428.457154284698;5177.559907407913;5298.470152984825;5363.946360536344;5390.97304513475;5412.458754124714;5302.5227729502985;5321.813736519203;5343.278657381204;5376.956756432531;5385.8114965976 01:59:35 INFO - PROCESS | 18130 | |5;yui.html;845.3152946113652;869.2698133567806;927.9818386386945;933.1881263300929;939.6147579492405;16051.678966420664;16703.749443758352;16822.747658785112;17227.655446891054;17990.100494975264;18125.274989000456;18332.08339583022;18357.449276521693;18645.90677046616;18706.906465467655;794.3962588432789;831.8170002599419;835.1940377535677;839.8110425154352;841.2722994609679;853.2661910756744;862.0345213361045;862.5299211929513;867.1328671328671;868.5049521772593;896.7847716548014;898.2454738020061;900.918917297443;901.733988473402;902.368342160487;893.3433926064338;893.6514759243868;893.8882639670041;894.4409743910023;894.5303715549323;663.5090033375268;664.1432552007903;666.9666516674196;667.2626747444053;667.813012356541;18231.17959691817;18344.816551834578;18524.44426667202;18823.341183058663;19083.713744293847;15002.24988750558;15990.520284391487;16170.27233774483;16312.83687163125;16518.2566784495;13406.865931340659;13455.259960702157;13663.590092297247;13702.588922332247;14096.436142554285;4089.5501494835544;4089.603561880724;4108.712390132692;4130.524989626201;4227.239096962546;88477;90138.09861901363;90263;90310.09689903083;91340;12624;12845.614631561068;12937.2884491352;13092.345382730826;13172.209667419986;24759.25722228336;25010.374740631265;25036.371181026094;25041.12356067529;25133.748662513142;8969.730908072768;9077.319201059947;9309;9473.147416732389;9764.658236961814;4986.601071914238;4989.0770207511005;4992.375953005874;5026.321446604735;5195.636305458657;3980.9402858957137;4127.669786417079;4237.322028475429;4240.957590424056;4253.404523366731;652.5921299188008;653.2161406312349;658.8254112660148;663.0319733189501;663.0750103605443;836.9539675317794;867.8611422172422;871.930245580352;875.6147295190096;882.5984177199359;11399.259048161843;11767;11792.70518237051;11839.11206659504;12222.75554488923;846.6007097963752;849.8895143631412;879.0550158579553;882.2456799436518;888.7482884922389;5418.783248670009;5478.397376288605;5625.690587017753;5663.037283661787;5672.177534257592;74942.6252868743;75667.62166189133;77055.61472192603;78321.60839195768;81277.59361203274;57861.4213857856;62756.686216568625;63021.684891576166;64153.35846641567;65463;57464;58673.70663146657;58893.411065889646;58985;59379.703101484214;11617.593384231512;12214.877851221374;12365;12538.185017973803;12563;37560.624393755716;38410.80794596009;39056.41415378715;40042.599574003885;42251.7887410561;51821.74089129681;53098;53791.46208537865;54027;54318.45681543134;11302.321860688386;11670.299782013106;11802.704932376415;14315.427382904567;14331.498397556035;16505.66988660196;16528.504144875675;16569.502914912573;16603.505684488453;16827.24277407495;15293.388264469742;15370.615734606725;15765.290561924512;16120.113393763455;16366.26351814195;15038.62403439879;15089.094654320776;15212.239388030775;15470.613234669223;15886.68226635484;14337.966349670878;14686.559403217494;14701.338439770023;14815.18516481604;15448.304826283069;10909.890901090888;11286.548538058385;11370;11415.431527906154;11619.651410457362;12358.567450139202;12481;12562.87437125617;12775.872241277468;12878.935605321913 01:59:35 INFO - PROCESS | 18130 | __end_tp_report 01:59:35 INFO - PROCESS | 18130 | __start_cc_report 01:59:35 INFO - PROCESS | 18130 | _x_x_mozilla_cycle_collect,1392 01:59:35 INFO - PROCESS | 18130 | __end_cc_report 01:59:35 INFO - PROCESS | 18130 | __startTimestamp1491987575433__endTimestamp 01:59:35 INFO - PROCESS | 18130 | 01:59:35 INFO - PROCESS | 18130 | ------- Summary: start ------- 01:59:35 INFO - PROCESS | 18130 | Number of tests: 6 01:59:35 INFO - PROCESS | 18130 | 01:59:35 INFO - PROCESS | 18130 | [#0] dojo.html Cycles:165 Average:5694.25 Median:5896.00 stddev:649.47 (11.0%) stddev-sans-first:625.57 01:59:35 INFO - PROCESS | 18130 | Values: 3380.1 3399.2 3411.3 3417.5 3444.4 5652.8 5748.3 5753.9 5757.0 5762.0 5754.4 5821.4 5826.7 5832.0 5837.8 5119.5 5155.7 5163.3 5189.8 5223.4 5003.5 5018.7 5240.5 5259.5 5281.8 5088.2 5116.9 5171.5 5189.5 5193.6 5171.0 5204.8 5208.5 5229.7 5242.9 4927.7 5063.6 5130.1 5181.1 5187.2 5569.0 5630.0 5690.7 5707.5 5712.8 6104.8 6134.5 6143.8 6159.9 6178.0 6213.2 6226.5 6227.8 6232.2 6243.4 5439.8 5448.3 5696.8 5701.7 5703.2 6243.7 6252.0 6258.2 6261.7 6317.8 6027.6 6070.3 6070.7 6072.0 6081.7 5925.9 5961.5 6105.9 6124.0 6131.5 6139.2 6140.3 6148.9 6151.8 6162.5 5882.5 5910.7 5912.2 6074.2 6159.2 4700.3 4718.4 4754.7 4777.6 4780.7 4123.6 4128.3 4216.1 4230.3 4235.6 5878.4 5966.3 5987.5 6043.1 6052.5 6191.9 6222.3 6229.5 6232.9 6235.9 5745.3 5787.4 6001.7 6007.8 6035.1 5176.3 5200.2 5227.0 5238.7 5289.7 6446.9 6474.0 6480.4 6480.6 6481.4 6117.1 6130.4 6176.7 6180.5 6182.9 6075.8 6117.0 6383.2 6426.5 6462.2 6059.8 6062.5 6094.3 6102.5 6121.4 6183.1 6198.0 6234.4 6235.4 6237.6 5727.0 5754.6 5864.6 5984.0 6086.4 5893.3 5896.0 5922.1 5936.0 5972.9 5848.2 5876.0 5883.4 5890.7 5892.1 5794.7 5827.2 5835.6 6015.2 6070.4 5846.5 5899.2 5902.4 5922.5 5939.0 01:59:35 INFO - PROCESS | 18130 | 01:59:35 INFO - PROCESS | 18130 | [#1] ext.html Cycles:180 Average:45242.81 Median:30196.55 stddev:72774.33 (241.0%) stddev-sans-first:72901.89 01:59:35 INFO - PROCESS | 18130 | Values: 773.8 840.1 855.4 912.9 928.1 32496.2 44214.0 44699.6 45127.0 45237.3 46408.8 46643.0 46656.0 46717.0 46746.5 7723.1 7863.8 8822.6 8939.8 8951.1 10577.2 10837.6 11129.3 11142.9 11145.8 10830.4 10877.6 10910.0 10917.7 10920.5 10825.0 10830.2 10868.9 10874.2 10879.9 10546.4 10591.8 10618.3 10620.8 10623.0 43660.8 43953.8 44079.3 44094.6 44116.8 4224.1 4256.2 4308.5 4394.1 4396.6 30206.0 30578.0 30595.5 31039.0 32012.8 4245.3 4342.0 4350.9 4354.6 4407.6 242320.0 242666.0 242720.0 242783.0 242830.0 54470.5 54661.0 54739.7 54843.5 54866.5 56869.0 57002.4 57064.7 57169.0 57175.7 16451.3 16789.5 16790.8 16805.1 16813.6 12889.9 13110.8 14723.8 14984.2 15002.2 13640.2 13843.5 13847.5 13880.9 13945.9 5509.1 5640.4 5677.2 5691.4 5713.4 1038.5 1042.5 1050.8 1062.1 1081.0 28452.7 28542.9 28570.0 28841.0 28899.0 1109.3 1120.0 1126.3 1133.0 1141.1 14723.5 14749.2 15163.5 15174.8 15282.8 297230.0 297613.0 300465.0 300748.0 301074.0 57884.0 58014.4 58031.1 58034.7 58106.1 1508.3 1571.2 1574.1 1581.7 1591.6 26252.7 27329.9 27515.2 27890.6 28175.0 26118.9 26135.3 26148.0 26174.7 26188.5 289919.0 292354.0 292776.0 293351.0 293389.0 30187.1 30918.0 35815.8 36111.6 36428.8 44897.3 45039.3 45257.1 45309.3 45742.0 34897.3 35228.8 35232.6 35274.0 35751.6 34132.3 34394.3 34539.5 34645.1 35525.0 39239.6 39606.8 39743.2 40214.0 40580.0 32010.4 32305.0 32537.8 32565.5 33167.0 26007.6 26241.7 26505.3 26771.2 27162.3 01:59:35 INFO - PROCESS | 18130 | 01:59:35 INFO - PROCESS | 18130 | [#2] jquery.html Cycles:180 Average:37934.19 Median:21127.63 stddev:109688.30 (519.2%) stddev-sans-first:109959.90 01:59:35 INFO - PROCESS | 18130 | Values: 450.7 467.3 471.2 479.4 487.4 19445.0 21282.6 21720.2 21909.8 21942.9 22035.0 22273.6 22475.4 22782.9 22835.2 5003.8 6568.8 6769.8 6856.8 6893.0 4167.9 4312.9 6399.1 7336.4 7652.7 7594.2 7797.8 7867.8 7879.5 7882.8 7737.3 7756.6 7859.5 7870.0 7874.4 7495.2 7547.2 7579.8 7590.3 7609.7 21037.5 21106.6 21148.7 24288.1 24753.5 2890.2 3010.1 3048.7 3068.2 3089.7 16168.4 16964.1 17179.0 17214.7 17414.7 2257.9 2291.3 2355.6 2387.6 2393.6 85101.0 102263.7 104879.5 106149.0 107678.5 18716.6 18916.2 19157.1 19269.4 19644.0 34828.0 34828.5 34883.3 34998.5 35096.3 10964.0 11303.3 11351.3 11359.3 11652.5 9439.5 9440.5 9486.4 9657.8 9691.5 10001.4 10201.6 10220.5 10236.4 10500.7 3238.6 3380.2 3894.3 3905.4 3944.8 985.6 1000.8 1011.8 1022.0 1044.9 21285.6 21376.9 21393.6 22231.4 22428.3 1092.4 1103.0 1114.2 1117.5 1141.8 11176.8 11332.0 11430.3 11492.3 11879.5 644198.0 660557.3 671722.0 673823.0 682285.0 113952.0 117519.0 118925.0 119250.0 122128.4 1273.2 1307.8 1351.8 1358.5 1361.1 19128.8 19226.2 19440.3 19534.0 19547.0 52760.5 53034.0 53094.0 53543.7 53694.0 69100.7 69341.3 70929.6 70997.3 71044.9 12230.8 12830.3 12849.4 13017.7 13018.0 10846.7 13921.0 14013.2 14456.4 14696.6 12345.4 12615.1 12620.7 12720.1 12797.7 10039.8 10552.7 10799.8 10964.3 11399.4 12159.9 12244.9 12794.9 12866.2 12935.0 12190.3 12499.4 12531.8 12768.4 12921.7 11035.0 11316.4 11339.9 11840.3 12576.6 01:59:35 INFO - PROCESS | 18130 | 01:59:35 INFO - PROCESS | 18130 | [#3] mootools.html Cycles:180 Average:7126.19 Median:2445.65 stddev:4577.47 (187.2%) stddev-sans-first:4570.78 01:59:35 INFO - PROCESS | 18130 | Values: 1498.1 1520.1 1577.0 1579.9 1584.9 10903.9 11501.3 11599.9 11609.0 11621.8 11224.3 11489.5 11492.2 11519.0 11527.8 2439.5 2447.8 2473.9 2478.2 2479.5 2394.4 2413.9 2467.1 2469.8 2471.1 2419.5 2429.3 2460.5 2507.7 2515.8 2412.8 2426.5 2443.5 2449.3 2454.5 2388.3 2400.8 2406.4 2414.9 2418.2 11446.2 11603.5 11605.0 11611.9 11616.9 5724.5 5846.9 5859.4 5864.6 5874.8 3401.1 3458.7 3499.1 3607.4 3635.5 1310.6 1342.6 1369.2 1379.4 1440.4 14555.4 15304.2 15352.5 17558.5 17757.4 4735.1 4752.2 4865.4 4886.2 4906.5 11098.7 11408.1 13415.9 13714.6 13755.9 5774.3 5954.5 6029.0 6032.2 6059.7 5495.2 5551.4 5552.0 5577.8 5590.8 3956.1 4008.2 4025.9 4068.1 4087.2 1005.2 1013.2 1022.4 1038.8 1040.5 906.4 917.9 918.4 934.4 941.1 6457.3 6644.2 6668.8 6773.8 6841.3 1035.3 1035.6 1040.0 1048.3 1066.8 4228.2 4233.8 4244.3 4269.8 4270.0 14417.9 14981.6 15019.4 15054.1 15076.2 14509.5 14860.0 14931.9 15021.1 15051.2 10286.5 10432.9 10436.8 10838.9 10969.6 6573.5 6826.4 6837.7 6843.5 6936.3 13173.3 13173.9 13469.4 13583.8 13619.5 13051.7 13143.5 13391.1 13557.2 13633.1 8690.1 8919.8 10476.5 10900.9 10906.8 10940.0 11163.7 11385.4 11471.4 11517.3 8795.5 9125.0 9256.8 9551.6 9573.4 9073.4 9159.6 9171.9 9203.8 9278.9 8307.1 8403.8 8408.2 8409.1 8419.7 6338.5 6349.7 6365.5 6421.3 6485.6 5961.4 6049.8 6054.1 6065.3 6156.7 01:59:35 INFO - PROCESS | 18130 | 01:59:35 INFO - PROCESS | 18130 | [#4] prototype.html Cycles:180 Average:4721.84 Median:5206.36 stddev:1251.17 (24.0%) stddev-sans-first:1212.43 01:59:35 INFO - PROCESS | 18130 | Values: 426.4 429.1 432.1 432.1 436.5 5071.8 5187.8 5197.2 5201.6 5211.1 4974.9 5050.3 5058.9 5059.3 5087.3 4165.2 4279.9 4285.9 4286.7 4306.9 4283.6 4315.4 4319.4 4334.4 4366.7 4307.0 4328.6 4373.9 4382.5 4382.8 4117.5 4248.8 4254.9 4352.7 4369.3 3976.2 3987.3 3995.4 4008.3 4050.6 5024.4 5030.7 5031.6 5052.7 5085.5 4813.9 4955.6 5001.6 5016.5 5047.1 5367.0 5381.8 5395.1 5411.5 5434.8 4480.8 4482.8 4542.2 4545.5 4552.1 5428.9 5556.6 5577.6 5669.3 5683.3 4631.2 4650.4 4659.3 4679.5 4683.8 4697.3 4702.7 4729.4 4733.5 4743.6 4803.8 4935.5 4953.0 4988.7 5026.6 5331.9 5367.8 5434.7 5446.7 5448.7 1621.3 1635.2 1650.5 1651.0 1655.8 1166.3 1255.7 1260.2 1290.3 1297.1 5312.8 5324.2 5332.7 5383.8 5407.7 5290.4 5628.7 5631.9 5636.0 5636.4 5334.4 5378.5 5406.0 5406.9 5440.8 2914.8 2918.8 2920.8 2924.9 2935.9 5506.3 5740.9 5745.0 5780.1 5804.3 5422.6 5625.5 5640.8 5647.2 5673.9 5639.5 5713.9 5755.9 5788.0 5790.8 5289.4 5437.5 5500.2 5529.7 5561.6 5075.4 5374.4 5395.8 5476.7 5514.3 5731.7 5735.9 5736.1 5763.9 5770.2 4405.4 4589.7 4688.4 4730.8 4765.2 5550.9 5551.7 5560.3 5566.2 5603.9 5289.8 5429.9 5451.2 5463.8 5469.6 5393.6 5399.8 5403.1 5412.9 5501.0 5366.4 5384.3 5384.4 5396.6 5428.5 5177.6 5298.5 5363.9 5391.0 5412.5 5302.5 5321.8 5343.3 5377.0 5385.8 01:59:35 INFO - PROCESS | 18130 | 01:59:35 INFO - PROCESS | 18130 | [#5] yui.html Cycles:180 Average:18395.41 Median:23239.51 stddev:22325.35 (96.1%) stddev-sans-first:22349.08 01:59:35 INFO - PROCESS | 18130 | Values: 845.3 869.3 928.0 933.2 939.6 16051.7 16703.7 16822.7 17227.7 17990.1 18125.3 18332.1 18357.4 18645.9 18706.9 794.4 831.8 835.2 839.8 841.3 853.3 862.0 862.5 867.1 868.5 896.8 898.2 900.9 901.7 902.4 893.3 893.7 893.9 894.4 894.5 663.5 664.1 667.0 667.3 667.8 18231.2 18344.8 18524.4 18823.3 19083.7 15002.2 15990.5 16170.3 16312.8 16518.3 13406.9 13455.3 13663.6 13702.6 14096.4 4089.6 4089.6 4108.7 4130.5 4227.2 88477.0 90138.1 90263.0 90310.1 91340.0 12624.0 12845.6 12937.3 13092.3 13172.2 24759.3 25010.4 25036.4 25041.1 25133.7 8969.7 9077.3 9309.0 9473.1 9764.7 4986.6 4989.1 4992.4 5026.3 5195.6 3980.9 4127.7 4237.3 4241.0 4253.4 652.6 653.2 658.8 663.0 663.1 837.0 867.9 871.9 875.6 882.6 11399.3 11767.0 11792.7 11839.1 12222.8 846.6 849.9 879.1 882.2 888.7 5418.8 5478.4 5625.7 5663.0 5672.2 74942.6 75667.6 77055.6 78321.6 81277.6 57861.4 62756.7 63021.7 64153.4 65463.0 57464.0 58673.7 58893.4 58985.0 59379.7 11617.6 12214.9 12365.0 12538.2 12563.0 37560.6 38410.8 39056.4 40042.6 42251.8 51821.7 53098.0 53791.5 54027.0 54318.5 11302.3 11670.3 11802.7 14315.4 14331.5 16505.7 16528.5 16569.5 16603.5 16827.2 15293.4 15370.6 15765.3 16120.1 16366.3 15038.6 15089.1 15212.2 15470.6 15886.7 14338.0 14686.6 14701.3 14815.2 15448.3 10909.9 11286.5 11370.0 11415.4 11619.7 12358.6 12481.0 12562.9 12775.9 12878.9 01:59:35 INFO - PROCESS | 18130 | -------- Summary: end -------- 01:59:35 INFO - PROCESS | 18130 | 01:59:36 INFO - TEST-INFO | 18130: exit 0 01:59:36 INFO - TEST-OK | dromaeo_css | took 1115205ms 01:59:36 INFO - TEST-START | kraken 01:59:36 INFO - operating with platform_type : linux_ 01:59:36 INFO - Initialising browser for kraken test... 01:59:36 INFO - TEST-INFO | started process 18453 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpb718Ev/profile http://localhost:35239/getInfo.html) 01:59:39 INFO - PROCESS | 18453 | __metrics Screen width/height:1600/1200 01:59:39 INFO - PROCESS | 18453 | colorDepth:24 01:59:39 INFO - PROCESS | 18453 | Browser inner width/height: 1024/697 01:59:39 INFO - PROCESS | 18453 | __metrics 01:59:40 INFO - PROCESS | 18453 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 01:59:40 INFO - PROCESS | 18453 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:59:41 INFO - TEST-INFO | 18453: exit 0 01:59:41 INFO - Browser initialized. 01:59:41 INFO - Running cycle 1/1 for kraken test... 01:59:41 INFO - TEST-INFO | started process 18543 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpb718Ev/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:59:53 INFO - PROCESS | 18543 | RSS: Main: 140357632 01:59:53 INFO - PROCESS | 18543 | 01:59:56 INFO - PROCESS | 18543 | Cycle 1(1): loaded http://localhost:35239/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35239/tests/kraken/driver.html?testName=audio-beat-detection) 01:59:56 INFO - PROCESS | 18543 | RSS: Main: 183775232 01:59:56 INFO - PROCESS | 18543 | 01:59:59 INFO - PROCESS | 18543 | Cycle 1(1): loaded http://localhost:35239/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35239/tests/kraken/driver.html?testName=audio-dft) 02:00:00 INFO - PROCESS | 18543 | RSS: Main: 174424064 02:00:00 INFO - PROCESS | 18543 | 02:00:02 INFO - PROCESS | 18543 | Cycle 1(1): loaded http://localhost:35239/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35239/tests/kraken/driver.html?testName=audio-fft) 02:00:03 INFO - PROCESS | 18543 | RSS: Main: 266002432 02:00:03 INFO - PROCESS | 18543 | 02:00:05 INFO - PROCESS | 18543 | Cycle 1(1): loaded http://localhost:35239/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35239/tests/kraken/driver.html?testName=audio-oscillator) 02:00:06 INFO - PROCESS | 18543 | RSS: Main: 172851200 02:00:06 INFO - PROCESS | 18543 | 02:00:08 INFO - PROCESS | 18543 | Cycle 1(1): loaded http://localhost:35239/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35239/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:00:08 INFO - PROCESS | 18543 | RSS: Main: 175071232 02:00:08 INFO - PROCESS | 18543 | 02:00:11 INFO - PROCESS | 18543 | Cycle 1(1): loaded http://localhost:35239/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35239/tests/kraken/driver.html?testName=imaging-darkroom) 02:00:12 INFO - PROCESS | 18543 | RSS: Main: 213442560 02:00:12 INFO - PROCESS | 18543 | 02:00:15 INFO - PROCESS | 18543 | Cycle 1(1): loaded http://localhost:35239/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35239/tests/kraken/driver.html?testName=imaging-desaturate) 02:00:16 INFO - PROCESS | 18543 | RSS: Main: 211976192 02:00:16 INFO - PROCESS | 18543 | 02:00:19 INFO - PROCESS | 18543 | Cycle 1(1): loaded http://localhost:35239/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35239/tests/kraken/driver.html?testName=json-parse-financial) 02:00:19 INFO - PROCESS | 18543 | RSS: Main: 210845696 02:00:19 INFO - PROCESS | 18543 | 02:00:21 INFO - PROCESS | 18543 | Cycle 1(1): loaded http://localhost:35239/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35239/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:00:21 INFO - PROCESS | 18543 | RSS: Main: 173264896 02:00:21 INFO - PROCESS | 18543 | 02:00:23 INFO - PROCESS | 18543 | Cycle 1(1): loaded http://localhost:35239/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35239/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:00:24 INFO - PROCESS | 18543 | RSS: Main: 180588544 02:00:24 INFO - PROCESS | 18543 | 02:00:26 INFO - PROCESS | 18543 | Cycle 1(1): loaded http://localhost:35239/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35239/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:00:26 INFO - PROCESS | 18543 | RSS: Main: 190226432 02:00:26 INFO - PROCESS | 18543 | 02:00:29 INFO - PROCESS | 18543 | Cycle 1(1): loaded http://localhost:35239/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35239/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:00:30 INFO - PROCESS | 18543 | RSS: Main: 198766592 02:00:30 INFO - PROCESS | 18543 | 02:00:32 INFO - PROCESS | 18543 | Cycle 1(1): loaded http://localhost:35239/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35239/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:00:33 INFO - PROCESS | 18543 | RSS: Main: 195035136 02:00:33 INFO - PROCESS | 18543 | 02:00:35 INFO - PROCESS | 18543 | Cycle 1(1): loaded http://localhost:35239/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35239/tests/kraken/driver.html?testName=ai-astar) 02:00:35 INFO - PROCESS | 18543 | RSS: Main: 246300672 02:00:35 INFO - PROCESS | 18543 | 02:00:35 INFO - PROCESS | 18543 | __start_tp_report 02:00:35 INFO - PROCESS | 18543 | _x_x_mozilla_page_load 02:00:35 INFO - PROCESS | 18543 | _x_x_mozilla_page_load_details 02:00:35 INFO - PROCESS | 18543 | |i|pagename|runs| 02:00:35 INFO - PROCESS | 18543 | |0;ai-astar;104;111;111;113;112;113;111;112;110;113 02:00:35 INFO - PROCESS | 18543 | |1;audio-beat-detection;152;115;115;154;118;117;114;116;132;116 02:00:35 INFO - PROCESS | 18543 | |2;audio-dft;160;161;163;163;163;164;163;163;161;162 02:00:35 INFO - PROCESS | 18543 | |3;audio-fft;73;75;75;110;75;76;75;74;97;76 02:00:35 INFO - PROCESS | 18543 | |4;audio-oscillator;82;82;111;82;83;82;96;83;83;108 02:00:35 INFO - PROCESS | 18543 | |5;imaging-gaussian-blur;113;113;114;113;113;114;113;113;113;114 02:00:35 INFO - PROCESS | 18543 | |6;imaging-darkroom;146;147;147;147;146;146;146;146;146;146 02:00:35 INFO - PROCESS | 18543 | |7;imaging-desaturate;93;94;94;93;94;94;94;94;94;93 02:00:35 INFO - PROCESS | 18543 | |8;json-parse-financial;55;54;55;55;55;54;55;55;54;54 02:00:35 INFO - PROCESS | 18543 | |9;json-stringify-tinderbox;55;55;53;54;54;55;54;54;54;54 02:00:35 INFO - PROCESS | 18543 | |10;stanford-crypto-aes;79;79;77;85;75;76;79;85;83;83 02:00:35 INFO - PROCESS | 18543 | |11;stanford-crypto-ccm;128;122;121;121;121;126;121;120;160;130 02:00:35 INFO - PROCESS | 18543 | |12;stanford-crypto-pbkdf2;163;163;160;160;163;169;158;165;159;158 02:00:35 INFO - PROCESS | 18543 | |13;stanford-crypto-sha256-iterative;63;71;59;59;63;62;60;59;64;58 02:00:35 INFO - PROCESS | 18543 | __end_tp_report 02:00:35 INFO - PROCESS | 18543 | __start_cc_report 02:00:35 INFO - PROCESS | 18543 | _x_x_mozilla_cycle_collect,1669 02:00:35 INFO - PROCESS | 18543 | __end_cc_report 02:00:35 INFO - PROCESS | 18543 | __startTimestamp1491987635257__endTimestamp 02:00:35 INFO - PROCESS | 18543 | 02:00:35 INFO - PROCESS | 18543 | ------- Summary: start ------- 02:00:35 INFO - PROCESS | 18543 | Number of tests: 14 02:00:35 INFO - PROCESS | 18543 | 02:00:35 INFO - PROCESS | 18543 | [#0] ai-astar Cycles:10 Average:111.00 Median:112.00 stddev:2.67 (2.4%) stddev-sans-first:1.09 02:00:35 INFO - PROCESS | 18543 | Values: 104.0 111.0 111.0 113.0 112.0 113.0 111.0 112.0 110.0 113.0 02:00:35 INFO - PROCESS | 18543 | 02:00:35 INFO - PROCESS | 18543 | [#1] audio-beat-detection Cycles:10 Average:124.90 Median:117.50 stddev:15.69 (13.4%) stddev-sans-first:13.22 02:00:35 INFO - PROCESS | 18543 | Values: 152.0 115.0 115.0 154.0 118.0 117.0 114.0 116.0 132.0 116.0 02:00:35 INFO - PROCESS | 18543 | 02:00:35 INFO - PROCESS | 18543 | [#2] audio-dft Cycles:10 Average:162.30 Median:163.00 stddev:1.25 (0.8%) stddev-sans-first:1.01 02:00:35 INFO - PROCESS | 18543 | Values: 160.0 161.0 163.0 163.0 163.0 164.0 163.0 163.0 161.0 162.0 02:00:35 INFO - PROCESS | 18543 | 02:00:35 INFO - PROCESS | 18543 | [#3] audio-fft Cycles:10 Average:80.60 Median:75.00 stddev:12.48 (16.6%) stddev-sans-first:12.93 02:00:35 INFO - PROCESS | 18543 | Values: 73.0 75.0 75.0 110.0 75.0 76.0 75.0 74.0 97.0 76.0 02:00:35 INFO - PROCESS | 18543 | 02:00:35 INFO - PROCESS | 18543 | [#4] audio-oscillator Cycles:10 Average:89.20 Median:82.50 stddev:11.54 (14.0%) stddev-sans-first:11.94 02:00:35 INFO - PROCESS | 18543 | Values: 82.0 82.0 111.0 82.0 83.0 82.0 96.0 83.0 83.0 108.0 02:00:35 INFO - PROCESS | 18543 | 02:00:35 INFO - PROCESS | 18543 | [#5] imaging-gaussian-blur Cycles:10 Average:113.30 Median:113.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 02:00:35 INFO - PROCESS | 18543 | Values: 113.0 113.0 114.0 113.0 113.0 114.0 113.0 113.0 113.0 114.0 02:00:35 INFO - PROCESS | 18543 | 02:00:35 INFO - PROCESS | 18543 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 02:00:35 INFO - PROCESS | 18543 | Values: 146.0 147.0 147.0 147.0 146.0 146.0 146.0 146.0 146.0 146.0 02:00:35 INFO - PROCESS | 18543 | 02:00:35 INFO - PROCESS | 18543 | [#7] imaging-desaturate Cycles:10 Average:93.70 Median:94.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 02:00:35 INFO - PROCESS | 18543 | Values: 93.0 94.0 94.0 93.0 94.0 94.0 94.0 94.0 94.0 93.0 02:00:35 INFO - PROCESS | 18543 | 02:00:35 INFO - PROCESS | 18543 | [#8] json-parse-financial Cycles:10 Average:54.60 Median:55.00 stddev:0.52 (0.9%) stddev-sans-first:0.53 02:00:35 INFO - PROCESS | 18543 | Values: 55.0 54.0 55.0 55.0 55.0 54.0 55.0 55.0 54.0 54.0 02:00:35 INFO - PROCESS | 18543 | 02:00:35 INFO - PROCESS | 18543 | [#9] json-stringify-tinderbox Cycles:10 Average:54.20 Median:54.00 stddev:0.63 (1.2%) stddev-sans-first:0.60 02:00:35 INFO - PROCESS | 18543 | Values: 55.0 55.0 53.0 54.0 54.0 55.0 54.0 54.0 54.0 54.0 02:00:35 INFO - PROCESS | 18543 | 02:00:35 INFO - PROCESS | 18543 | [#10] stanford-crypto-aes Cycles:10 Average:80.10 Median:81.00 stddev:3.67 (4.5%) stddev-sans-first:3.87 02:00:35 INFO - PROCESS | 18543 | Values: 79.0 79.0 77.0 85.0 75.0 76.0 79.0 85.0 83.0 83.0 02:00:35 INFO - PROCESS | 18543 | 02:00:35 INFO - PROCESS | 18543 | [#11] stanford-crypto-ccm Cycles:10 Average:127.00 Median:124.00 stddev:12.10 (9.8%) stddev-sans-first:12.83 02:00:35 INFO - PROCESS | 18543 | Values: 128.0 122.0 121.0 121.0 121.0 126.0 121.0 120.0 160.0 130.0 02:00:35 INFO - PROCESS | 18543 | 02:00:35 INFO - PROCESS | 18543 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.80 Median:163.00 stddev:3.49 (2.1%) stddev-sans-first:3.67 02:00:35 INFO - PROCESS | 18543 | Values: 163.0 163.0 160.0 160.0 163.0 169.0 158.0 165.0 159.0 158.0 02:00:35 INFO - PROCESS | 18543 | 02:00:35 INFO - PROCESS | 18543 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.80 Median:62.50 stddev:3.85 (6.2%) stddev-sans-first:4.06 02:00:35 INFO - PROCESS | 18543 | Values: 63.0 71.0 59.0 59.0 63.0 62.0 60.0 59.0 64.0 58.0 02:00:35 INFO - PROCESS | 18543 | -------- Summary: end -------- 02:00:35 INFO - PROCESS | 18543 | 02:00:36 INFO - TEST-INFO | 18543: exit 0 02:00:36 INFO - TEST-OK | kraken | took 60091ms 02:00:36 INFO - SUITE-END | took 1175s 02:00:36 INFO - Completed test suite (00:19:35) 02:00:36 INFO - javascript benchmark 02:00:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7858.135274661454, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3380.104272367823, 3399.1841957930087, 3411.266577685797, 3417.4532074868025, 3444.3627928833166, 5652.773889044433, 5748.25272714547, 5753.884922301546, 5756.971215143929, 5762.049261871786, 5754.367019627837, 5821.359650438459, 5826.679532625704, 5832.037713777222, 5837.795677151301, 5119.539241468267, 5155.664881782672, 5163.302954101196, 5189.844304670847, 5223.425423203445, 5003.499650034985, 5018.723970181639, 5240.528352448279, 5259.526642602165, 5281.815136470224, 5088.160453525183, 5116.948830511703, 5171.482851714817, 5189.481051894818, 5193.610479214055, 5171.0, 5204.843854684365, 5208.479152084799, 5229.7385130743505, 5242.947570524322, 4927.6550641455115, 5063.620228482841, 5130.07658621448, 5181.093308670967, 5187.19598462239, 5569.0254205514275, 5630.042892708249, 5690.687012214326, 5707.543396528268, 5712.771489140429, 6104.816855494342, 6134.539909506805, 6143.815685529441, 6159.938400615982, 6178.0, 6213.2233470816145, 6226.470749986257, 6227.813165605039, 6232.220972378453, 6243.406876346785, 5439.782408703687, 5448.264484294609, 5696.800611978644, 5701.686407247558, 5703.230063941356, 6243.687815609293, 6251.9687401563615, 6258.15514905491, 6261.7182226799905, 6317.842053948596, 6027.608205466719, 6070.3019152798, 6070.696465176724, 6071.969640151771, 6081.665508397081, 5925.911111333334, 5961.5230781537375, 6105.877882442415, 6123.969380153071, 6131.509479241739, 6139.185504090456, 6140.324564297924, 6148.938510614926, 6151.815445536641, 6162.537809664203, 5882.470577647947, 5910.734016969247, 5912.17229587858, 6074.210352654215, 6159.23009623797, 4700.341952126705, 4718.363020992191, 4754.7147171169845, 4777.570018698334, 4780.713157210578, 4123.60825721562, 4128.298189307825, 4216.114615930689, 4230.277355926764, 4235.5552666969525, 5878.382769809194, 5966.31387390447, 5987.520998320124, 6043.123747056652, 6052.54605904559, 6191.854969503405, 6222.253329600477, 6229.470495007885, 6232.937670623236, 6235.906461403082, 5745.2818397700285, 5787.421257874179, 6001.669908155006, 6007.759689612454, 6035.064564992434, 5176.301199338041, 5200.219967004904, 5226.973865130726, 5238.685678859204, 5289.68261904287, 6446.935530644727, 6474.0, 6480.416762491398, 6480.61116333031, 6481.449076828428, 6117.051856962178, 6130.417610327012, 6176.66028368444, 6180.47465965398, 6182.876342473215, 6075.787347442818, 6117.021276595723, 6383.202099737533, 6426.518011149276, 6462.192225971754, 6059.787907423219, 6062.545309101747, 6094.329623741473, 6102.511799055976, 6121.387861213843, 6183.134361189527, 6198.024948024883, 6234.376562343729, 6235.438810507076, 6237.625742455467, 5726.971365143314, 5754.56840736938, 5864.560157988082, 5983.981067630534, 6086.391360864055, 5893.322267939242, 5896.0, 5922.1412895130825, 5935.9703201485445, 5972.8805423892145, 5848.239728835224, 5876.030454974882, 5883.441073098049, 5890.705464726832, 5892.145638882253, 5794.681292528783, 5827.155062515996, 5835.56233282497, 6015.187949626745, 6070.423309785563, 5846.473817356288, 5899.233099697012, 5902.350741418355, 5922.466977971831, 5939.020061689773], "value": 5651.259171023587, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [773.7794728502374, 840.071720748573, 855.3584811391454, 912.9497877616728, 928.1275600935116, 32496.18759531013, 44214.0, 44699.55300446995, 45126.999999999956, 45237.32144017834, 46408.767956160256, 46643.0, 46656.0, 46717.0, 46746.53253467464, 7723.11184213817, 7863.842723145563, 8822.647094116228, 8939.776505587348, 8951.149640784115, 10577.153827693766, 10837.566497340096, 11129.332240065582, 11142.944285278561, 11145.777084458306, 10830.40432776197, 10877.564897404094, 10910.0, 10917.672469825919, 10920.453977301142, 10825.0, 10830.241883068189, 10868.945655271673, 10874.238803283773, 10879.891201088007, 10546.367217966947, 10591.84112238317, 10618.309809862334, 10620.840687389695, 10623.0, 43660.78169609163, 43953.78023109896, 44079.33880991788, 44094.559054409365, 44116.77941610303, 4224.112936283384, 4256.212600668855, 4308.547602501723, 4394.121175764828, 4396.582324679151, 30206.0, 30578.0, 30595.541066884016, 31039.0, 32012.839935800173, 4245.278302688519, 4341.978290108561, 4350.934735978963, 4354.564543545652, 4407.625351845097, 242320.0, 242666.0, 242720.0, 242783.0, 242830.0, 54470.45529544733, 54661.0, 54739.72630136824, 54843.45156548383, 54866.451335486934, 56869.0, 57002.42997569971, 57064.71467642718, 57169.0, 57175.714121429126, 16451.25969331359, 16789.496315110566, 16790.83209167917, 16805.075720835226, 16813.579660508585, 12889.871101289054, 13110.803337949937, 14723.779143312859, 14984.175870327237, 15002.174880381684, 13640.181589104686, 13843.515476958259, 13847.515336963157, 13880.93059534696, 13945.860541394659, 5509.118541033496, 5640.407757185519, 5677.205191273225, 5691.373948865621, 5713.4000929902595, 1038.5378506564657, 1042.516363925756, 1050.821360368739, 1062.0600768319937, 1080.9621663241749, 28452.715472845008, 28542.857285713853, 28570.002645093053, 28841.0, 28899.0, 1109.3399427340719, 1119.9664010079712, 1126.3129491010477, 1132.9830052549219, 1141.1384404774342, 14723.484678036217, 14749.188794616184, 15163.545093646988, 15174.786017118604, 15282.770758438632, 297230.0, 297613.0, 300465.0, 300748.0, 301074.0, 57884.0, 58014.4198558009, 58031.129533057036, 58034.7098264506, 58106.12840807391, 1508.2986411318789, 1571.2065406969407, 1574.1027614259763, 1581.7390130628548, 1591.554364777864, 26252.73747262503, 27329.86335068312, 27515.17454476369, 27890.581641274992, 28175.0, 26118.86940565285, 26135.346616333984, 26148.0, 26174.738252617994, 26188.476230474902, 289919.0, 292354.0, 292776.0, 293351.0, 293389.0, 30187.09438716754, 30918.0, 35815.82092089523, 36111.63888361083, 36428.81785591055, 44897.32654010258, 45039.324410134526, 45257.094858103315, 45309.32036019397, 45742.0, 34897.30205395928, 35228.82385588055, 35232.64767352294, 35274.0, 35751.64248357587, 34132.317353653285, 34394.312113757085, 34539.4819077709, 34645.133871652404, 35525.0, 39239.60760392359, 39606.80196598999, 39743.2051358977, 40214.0, 40580.0, 32010.35979280448, 32305.0, 32537.837310813295, 32565.511517326784, 33167.0, 26007.609885852107, 26241.73758262393, 26505.337366565218, 26771.19686409411, 27162.32094197661], "value": 19108.64955889696, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [450.73857162845553, 467.2990514228657, 471.2460063897764, 479.4127194187119, 487.4272729542787, 19445.027748612567, 21282.57434851302, 21720.2397916073, 21909.780902190992, 21942.89028554859, 22035.00842462089, 22273.55452890945, 22475.438114047156, 22782.88608556959, 22835.200767973125, 5003.774830132635, 6568.835779105537, 6769.796906092801, 6856.760013399532, 6893.034975103489, 4167.874963751093, 4312.935305970413, 6399.072134540489, 7336.413086953057, 7652.732154374598, 7594.240575942416, 7797.844043119134, 7867.8033049173655, 7879.4878332908465, 7882.80292992674, 7737.264959828835, 7756.573388463632, 7859.528428294292, 7869.96065019677, 7874.3700503959835, 7495.175530691619, 7547.245275472464, 7579.848403031963, 7590.316871481538, 7609.6575654095695, 21037.47406314839, 21106.577868442546, 21148.682769758467, 24288.149914753074, 24753.5049299013, 2890.1762997545597, 3010.127063151683, 3048.695130486956, 3068.171593669719, 3089.6601373848857, 16168.434104806276, 16964.0669763163, 17179.0, 17214.741778873326, 17414.651706965917, 2257.8532395394245, 2291.346966114666, 2355.611324131516, 2387.6179811230118, 2393.557191919483, 85101.0, 102263.73844777944, 104879.4756026215, 106149.0, 107678.46160769224, 18716.625667486573, 18916.24335026611, 19157.13792879324, 19269.4219173425, 19644.0, 34828.0, 34828.477572836426, 34883.30233395318, 34998.47502287468, 35096.29807403889, 10964.0, 11303.321800691985, 11351.318920864775, 11359.261647992855, 11652.53389864413, 9439.52802359893, 9440.527973601293, 9486.430814151036, 9657.758556036155, 9691.51542422876, 10001.449920254456, 10201.591936322613, 10220.540075696612, 10236.385816851012, 10500.684979450629, 3238.578984732017, 3380.242274017497, 3894.3184942634925, 3905.3556163232747, 3944.8027598619956, 985.605757696927, 1000.7798284377425, 1011.7925825205815, 1022.0392830739081, 1044.9268551201494, 21285.574288514144, 21376.94560794934, 21393.57212855734, 22231.44421389478, 22428.32715018552, 1092.3718861654581, 1103.0293341859112, 1114.2256131988352, 1117.474786850179, 1141.8230174322994, 11176.776464470666, 11332.0, 11430.25703329281, 11492.310461372177, 11879.465424055938, 644198.0, 660557.2631631538, 671722.0, 673823.0, 682285.0, 113952.0, 117519.0, 118925.0, 119250.0, 122128.38935805263, 1273.1724379153625, 1307.7972914198315, 1351.7769568021167, 1358.4566173530666, 1361.1424802374638, 19128.808711913258, 19226.230950761812, 19440.31958881489, 19534.0, 19547.0, 52760.47239527556, 53034.0, 53094.0, 53543.73228133834, 53694.0, 69100.65449672719, 69341.3065869355, 70929.64535177498, 70997.29002709906, 71044.93432598619, 12230.755384892074, 12830.29433381173, 12849.421776020288, 13017.674558136123, 13018.0, 10846.67459976202, 13921.025528212927, 14013.229272390117, 14456.421743130157, 14696.55910322692, 12345.382730863601, 12615.053870959531, 12620.684482888002, 12720.109592328448, 12797.656246094026, 10039.849402258826, 10552.736181595521, 10799.838002429813, 10964.287321324407, 11399.352207293918, 12159.939200303941, 12244.938775306422, 12794.936025319814, 12866.228026318451, 12935.0, 12190.268583884996, 12499.375031248219, 12531.812022819848, 12768.425420855898, 12921.741565168832, 11035.0, 11316.37759923212, 11339.886601133883, 11840.28958262507, 12576.559820406243], "value": 11689.332963588193, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1498.101139316411, 1520.0803513874107, 1576.9986058852633, 1579.9210039498025, 1584.944526941557, 10903.945480272598, 11501.309921404722, 11599.942000290008, 11609.0, 11621.767564648713, 11224.270422422536, 11489.482973266202, 11492.195546311761, 11519.020883224935, 11527.769444611104, 2439.524292762912, 2447.755224477547, 2473.88867500962, 2478.2069737683946, 2479.4917042006437, 2394.389430695179, 2413.903443862252, 2467.0748469324003, 2469.8271121021535, 2471.1351027140536, 2419.5427659142, 2429.3197904586655, 2460.520198561281, 2507.736687647798, 2515.8238923275376, 2412.843165194257, 2426.5389575980594, 2443.5357282116343, 2449.2529778417575, 2454.496828150234, 2388.319328991229, 2400.8079353651665, 2406.350285422926, 2414.8792560372085, 2418.238254949703, 11446.198766086378, 11603.477843497063, 11605.0, 11611.883881161164, 11616.883831161666, 5724.4561766632105, 5846.883062338772, 5859.414058594107, 5864.648121112704, 5874.7943821966455, 3401.1327111586634, 3458.706009989154, 3499.055255081114, 3607.3867442534834, 3635.472856435802, 1310.6330227536305, 1342.6441992871896, 1369.1516452788755, 1379.4137491566144, 1440.387835170059, 14555.417783288656, 15304.234788260543, 15352.462663806713, 17558.473245802645, 17757.378491752726, 4735.058625963338, 4752.1683705351425, 4865.367502224688, 4886.169351210303, 4906.484819093944, 11098.667039988813, 11408.144389170679, 13415.865841341461, 13714.588562343146, 13755.931220344035, 5774.2782152230975, 5954.523638108941, 6028.969855150696, 6032.155498230251, 6059.697015149225, 5495.230667706524, 5551.417101204315, 5551.9722401387735, 5577.832665020056, 5590.8043218487155, 3956.1296514766705, 4008.2183974124764, 4025.8993525162105, 4068.105016896278, 4087.223427548816, 1005.1958433253368, 1013.2046343620223, 1022.4376592874038, 1038.8389799581078, 1040.4693606260862, 906.3655441191061, 917.8852643419573, 918.3984490158956, 934.3807626326882, 941.1153515695362, 6457.321981192001, 6644.202695676549, 6668.766593169215, 6773.830654233683, 6841.281665425158, 1035.2597892506894, 1035.6116456328878, 1039.9792004160026, 1048.2505008918508, 1066.7866426714688, 4228.217779710763, 4233.830646774156, 4244.3209086546, 4269.786510674454, 4270.0, 14417.85582144186, 14981.625459363604, 15019.399224031135, 15054.096754194783, 15076.170810605303, 14509.492167774077, 14860.0, 14931.92534037323, 15021.098734075771, 15051.172185529682, 10286.537105830106, 10432.895671043192, 10436.843447348296, 10838.945805270923, 10969.561217551209, 6573.474122070318, 6826.385625293748, 6837.658117094026, 6843.486738494732, 6936.34104760047, 13173.341332933507, 13173.934130329288, 13469.39387727535, 13583.79624305656, 13619.455221791017, 13051.738965220833, 13143.474261029452, 13391.129576577881, 13557.18656880551, 13633.113847599874, 8690.130986901511, 8919.777005574913, 10476.476176191007, 10900.890991089987, 10906.836397454203, 10940.0, 11163.665090047312, 11385.430728463709, 11471.369074700639, 11517.25137866036, 8795.47227166372, 9125.041870603756, 9256.814863702553, 9551.570179341807, 9573.425594464354, 9073.410228335137, 9159.587818548314, 9171.862422063541, 9203.815923681623, 9278.907210927804, 8307.127751585997, 8403.831923361377, 8408.2432581068, 8409.075001749925, 8419.663213471393, 6338.524610654127, 6349.714262858275, 6365.490760739222, 6421.293657697556, 6485.643289619116, 5961.403859614177, 6049.818505444844, 6054.061620448838, 6065.272167339771, 6156.722947467285], "value": 5370.012700226923, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [426.4179395125653, 429.0603578163822, 432.06458018390185, 432.1033854751392, 436.4827679200148, 5071.84784456466, 5187.792488300468, 5197.168453047513, 5201.609879259061, 5211.114110601197, 4974.850754477371, 5050.292958985736, 5058.924116138261, 5059.3169922060515, 5087.262346959688, 4165.1669666066755, 4279.935800962988, 4285.907093691105, 4286.699931004831, 4306.913861722764, 4283.614474697277, 4315.374270730742, 4319.3952846601505, 4334.436523251989, 4366.694331396803, 4307.0, 4328.63206627437, 4373.934390984122, 4382.458174258938, 4382.824687012516, 4117.464729585165, 4248.830046798124, 4254.893627659302, 4352.651787856979, 4369.322754972968, 3976.16500534888, 3987.282289187945, 3995.380715989026, 4008.3386241270155, 4050.5544390117066, 5024.397072351341, 5030.698158110525, 5031.572316353115, 5052.686301561584, 5085.466026067284, 4813.927791083137, 4955.578775804061, 5001.599872010231, 5016.548510634024, 5047.141985862412, 5367.0, 5381.847261095554, 5395.111710901153, 5411.485908838694, 5434.804343044524, 4480.820767169342, 4482.8431004915, 4542.227821270392, 4545.477270113913, 4552.132156741265, 5428.891422171534, 5556.555475561945, 5577.60559860039, 5669.291338582677, 5683.3464151622675, 4631.235846085426, 4650.441946966385, 4659.347691323217, 4679.532046795293, 4683.7892294846815, 4697.31888876111, 4702.741349225826, 4729.408823897013, 4733.502982186889, 4743.573078422958, 4803.759812009385, 4935.506449355035, 4952.975235123873, 4988.678000329915, 5026.648134630614, 5331.866703332447, 5367.78528858849, 5434.728263586805, 5446.673199608036, 5448.700321482356, 1621.3433559408459, 1635.2069246415717, 1650.529599064261, 1650.951645704962, 1655.78299949537, 1166.2827361172988, 1255.7174635707077, 1260.2438536877983, 1290.2967882504058, 1297.1179597873347, 5312.846538857302, 5324.201369794562, 5332.68003919766, 5383.81156659515, 5407.675539467645, 5290.418054014055, 5628.746706398222, 5631.887362252732, 5635.971820140873, 5636.408177141423, 5334.386150786087, 5378.462153784591, 5406.0, 5406.91889621656, 5440.836774896759, 2914.79596428248, 2918.8102773319665, 2920.7517361024125, 2924.9122526324245, 2935.9448386129366, 5506.256655351556, 5740.913886291709, 5745.0, 5780.092569452071, 5804.303483581997, 5422.566194704415, 5625.549956003591, 5640.830775076754, 5647.188148000084, 5673.893590749764, 5639.464250896158, 5713.857153571111, 5755.9424405756245, 5787.971060144672, 5790.7973220937065, 5289.43462420718, 5437.483439073281, 5500.22996780443, 5529.677535435047, 5561.555075594024, 5075.416327122428, 5374.381946076252, 5395.78416863321, 5476.67139971603, 5514.255575497256, 5731.656100633975, 5735.8566035849435, 5736.139579063007, 5763.8559036024435, 5770.192173095854, 4405.449318835145, 4589.678722489527, 4688.444797585256, 4730.763461826826, 4765.166095933268, 5550.916736248879, 5551.694656793915, 5560.304961879765, 5566.248556444828, 5603.915941260802, 5289.841304760864, 5429.864253393696, 5451.155070964007, 5463.808766693147, 5469.562435005269, 5393.622446428869, 5399.83800485986, 5403.055334842599, 5412.864678383072, 5500.97446404484, 5366.409694933476, 5384.250118711356, 5384.353877534721, 5396.64921780083, 5428.457154284698, 5177.559907407913, 5298.470152984825, 5363.946360536344, 5390.97304513475, 5412.458754124714, 5302.5227729502985, 5321.813736519203, 5343.278657381204, 5376.956756432531, 5385.8114965976], "value": 4363.8080646016215, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [845.3152946113652, 869.2698133567806, 927.9818386386945, 933.1881263300929, 939.6147579492405, 16051.678966420664, 16703.749443758352, 16822.747658785112, 17227.655446891054, 17990.100494975264, 18125.274989000456, 18332.08339583022, 18357.449276521693, 18645.90677046616, 18706.906465467655, 794.3962588432789, 831.8170002599419, 835.1940377535677, 839.8110425154352, 841.2722994609679, 853.2661910756744, 862.0345213361045, 862.5299211929513, 867.1328671328671, 868.5049521772593, 896.7847716548014, 898.2454738020061, 900.918917297443, 901.733988473402, 902.368342160487, 893.3433926064338, 893.6514759243868, 893.8882639670041, 894.4409743910023, 894.5303715549323, 663.5090033375268, 664.1432552007903, 666.9666516674196, 667.2626747444053, 667.813012356541, 18231.17959691817, 18344.816551834578, 18524.44426667202, 18823.341183058663, 19083.713744293847, 15002.24988750558, 15990.520284391487, 16170.27233774483, 16312.83687163125, 16518.2566784495, 13406.865931340659, 13455.259960702157, 13663.590092297247, 13702.588922332247, 14096.436142554285, 4089.5501494835544, 4089.603561880724, 4108.712390132692, 4130.524989626201, 4227.239096962546, 88477.0, 90138.09861901363, 90263.0, 90310.09689903083, 91340.0, 12624.0, 12845.614631561068, 12937.2884491352, 13092.345382730826, 13172.209667419986, 24759.25722228336, 25010.374740631265, 25036.371181026094, 25041.12356067529, 25133.748662513142, 8969.730908072768, 9077.319201059947, 9309.0, 9473.147416732389, 9764.658236961814, 4986.601071914238, 4989.0770207511005, 4992.375953005874, 5026.321446604735, 5195.636305458657, 3980.9402858957137, 4127.669786417079, 4237.322028475429, 4240.957590424056, 4253.404523366731, 652.5921299188008, 653.2161406312349, 658.8254112660148, 663.0319733189501, 663.0750103605443, 836.9539675317794, 867.8611422172422, 871.930245580352, 875.6147295190096, 882.5984177199359, 11399.259048161843, 11767.0, 11792.70518237051, 11839.11206659504, 12222.75554488923, 846.6007097963752, 849.8895143631412, 879.0550158579553, 882.2456799436518, 888.7482884922389, 5418.783248670009, 5478.397376288605, 5625.690587017753, 5663.037283661787, 5672.177534257592, 74942.6252868743, 75667.62166189133, 77055.61472192603, 78321.60839195768, 81277.59361203274, 57861.4213857856, 62756.686216568625, 63021.684891576166, 64153.35846641567, 65463.0, 57464.0, 58673.70663146657, 58893.411065889646, 58985.0, 59379.703101484214, 11617.593384231512, 12214.877851221374, 12365.0, 12538.185017973803, 12563.0, 37560.624393755716, 38410.80794596009, 39056.41415378715, 40042.599574003885, 42251.7887410561, 51821.74089129681, 53098.0, 53791.46208537865, 54027.0, 54318.45681543134, 11302.321860688386, 11670.299782013106, 11802.704932376415, 14315.427382904567, 14331.498397556035, 16505.66988660196, 16528.504144875675, 16569.502914912573, 16603.505684488453, 16827.24277407495, 15293.388264469742, 15370.615734606725, 15765.290561924512, 16120.113393763455, 16366.26351814195, 15038.62403439879, 15089.094654320776, 15212.239388030775, 15470.613234669223, 15886.68226635484, 14337.966349670878, 14686.559403217494, 14701.338439770023, 14815.18516481604, 15448.304826283069, 10909.890901090888, 11286.548538058385, 11370.0, 11415.431527906154, 11619.651410457362, 12358.567450139202, 12481.0, 12562.87437125617, 12775.872241277468, 12878.935605321913], "value": 7959.2293951122365, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1460.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 111.0, 111.0, 113.0, 112.0, 113.0, 111.0, 112.0, 110.0, 113.0], "value": 111.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 115.0, 115.0, 154.0, 118.0, 117.0, 114.0, 116.0, 132.0, 116.0], "value": 124.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 163.0, 163.0, 163.0, 164.0, 163.0, 163.0, 161.0, 162.0], "value": 162.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 75.0, 75.0, 110.0, 75.0, 76.0, 75.0, 74.0, 97.0, 76.0], "value": 80.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 111.0, 82.0, 83.0, 82.0, 96.0, 83.0, 83.0, 108.0], "value": 89.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 114.0, 113.0, 113.0, 114.0, 113.0, 113.0, 113.0, 114.0], "value": 113.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 147.0, 147.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 94.0, 93.0, 94.0, 94.0, 94.0, 94.0, 94.0, 93.0], "value": 93.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 55.0, 55.0, 55.0, 54.0, 55.0, 55.0, 54.0, 54.0], "value": 54.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 53.0, 54.0, 54.0, 55.0, 54.0, 54.0, 54.0, 54.0], "value": 54.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 77.0, 85.0, 75.0, 76.0, 79.0, 85.0, 83.0, 83.0], "value": 80.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 122.0, 121.0, 121.0, 121.0, 126.0, 121.0, 120.0, 160.0, 130.0], "value": 127.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 160.0, 160.0, 163.0, 169.0, 158.0, 165.0, 159.0, 158.0], "value": 161.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 71.0, 59.0, 59.0, 63.0, 62.0, 60.0, 59.0, 64.0, 58.0], "value": 61.8, "unit": "score"}]}]} 02:00:36 INFO - Return code: 0 02:00:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:00:36 ERROR - # TBPL SUCCESS # 02:00:36 INFO - Running post-action listener: _resource_record_post_action 02:00:36 INFO - [mozharness: 2017-04-12 09:00:36.580301Z] Finished run-tests step (success) 02:00:36 INFO - Running post-run listener: _resource_record_post_run 02:00:36 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 4096; Write bytes: 367099904; Read time: 16; Write time: 264204 02:00:36 INFO - TinderboxPrint: CPU usage
13.1% 02:00:36 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 02:00:36 INFO - TinderboxPrint: I/O write bytes / time
367,099,904 / 264,204 02:00:36 INFO - TinderboxPrint: CPU idle
8,263.6 (86.9%) 02:00:36 INFO - TinderboxPrint: CPU user
1,202.2 (12.6%) 02:00:36 INFO - TinderboxPrint: Swap in / out
0 / 0 02:00:36 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 112644096; Read time: 0; Write time: 109464 02:00:36 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 4096; Write bytes: 254455808; Read time: 16; Write time: 154740 02:00:37 INFO - Running post-run listener: _upload_blobber_files 02:00:37 INFO - Blob upload gear active. 02:00:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:00:37 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 02:00:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:00:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:00:37 INFO - (blobuploader) - INFO - Open directory for files ... 02:00:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 02:00:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:00:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:00:38 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 02:00:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:00:38 INFO - (blobuploader) - INFO - Done attempting. 02:00:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:00:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:00:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:00:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:00:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:00:49 INFO - (blobuploader) - INFO - Done attempting. 02:00:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:00:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:00:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:00:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:00:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:00:50 INFO - (blobuploader) - INFO - Done attempting. 02:00:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:00:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:00:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:00:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:00:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:00:50 INFO - (blobuploader) - INFO - Done attempting. 02:00:50 INFO - (blobuploader) - INFO - Iteration through files over. 02:00:50 INFO - Return code: 0 02:00:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:00:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:00:50 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/2d2fd499fadb03535754dad25f4ca24b384ee28e13f4e2433c46f1fd7de573e9a59059df20221f4280ec6cb9d3ab7d00669b1d329b036eef913d89cf2758935f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/bf28669d9dce63448756a602e0b1c2b59048ee480abeb83e7e99b78c82a2d1b6e0e40e94ad41af6b6d4ddab3fc01f10a57b806b97c9916e590c5dcc1da7748b4", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/45f8cda6bfc86f31da1455f0ba8d10ea9f2eaf0828a44e8778e8496cc45601315465deafeee285fe8ce6fd29ec015c3a5ec60679ca9b8b67174e60ac31348b6f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/e7edb29f509f3f89f009bfcc0b0c6f2475f43cc5375aa2d1b6909e3bb3a6a56141fb69a41a7413f4049320f507a7dade6975943b1c33ade7f25234f8cc651482"} 02:00:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:00:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:00:50 INFO - Contents: 02:00:50 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/2d2fd499fadb03535754dad25f4ca24b384ee28e13f4e2433c46f1fd7de573e9a59059df20221f4280ec6cb9d3ab7d00669b1d329b036eef913d89cf2758935f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/bf28669d9dce63448756a602e0b1c2b59048ee480abeb83e7e99b78c82a2d1b6e0e40e94ad41af6b6d4ddab3fc01f10a57b806b97c9916e590c5dcc1da7748b4", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/45f8cda6bfc86f31da1455f0ba8d10ea9f2eaf0828a44e8778e8496cc45601315465deafeee285fe8ce6fd29ec015c3a5ec60679ca9b8b67174e60ac31348b6f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/e7edb29f509f3f89f009bfcc0b0c6f2475f43cc5375aa2d1b6909e3bb3a6a56141fb69a41a7413f4049320f507a7dade6975943b1c33ade7f25234f8cc651482"} 02:00:50 INFO - Running post-run listener: copy_logs_to_upload_dir 02:00:50 INFO - Copying logs to upload dir... 02:00:50 INFO - mkdir: /builds/slave/test/build/upload/logs 02:00:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1258.344298 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2017-04-12 02:00:50.697360) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-12 02:00:50.701273) ========= 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=1f5564757af27664ca02d71f000001b0-1491986390.706528-1566323919 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/2d2fd499fadb03535754dad25f4ca24b384ee28e13f4e2433c46f1fd7de573e9a59059df20221f4280ec6cb9d3ab7d00669b1d329b036eef913d89cf2758935f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/bf28669d9dce63448756a602e0b1c2b59048ee480abeb83e7e99b78c82a2d1b6e0e40e94ad41af6b6d4ddab3fc01f10a57b806b97c9916e590c5dcc1da7748b4", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/45f8cda6bfc86f31da1455f0ba8d10ea9f2eaf0828a44e8778e8496cc45601315465deafeee285fe8ce6fd29ec015c3a5ec60679ca9b8b67174e60ac31348b6f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/e7edb29f509f3f89f009bfcc0b0c6f2475f43cc5375aa2d1b6909e3bb3a6a56141fb69a41a7413f4049320f507a7dade6975943b1c33ade7f25234f8cc651482"} build_url:https://queue.taskcluster.net/v1/task/KeVv12r4Q1ed-jtV1j3DjA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011321 build_url: 'https://queue.taskcluster.net/v1/task/KeVv12r4Q1ed-jtV1j3DjA/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/2d2fd499fadb03535754dad25f4ca24b384ee28e13f4e2433c46f1fd7de573e9a59059df20221f4280ec6cb9d3ab7d00669b1d329b036eef913d89cf2758935f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/bf28669d9dce63448756a602e0b1c2b59048ee480abeb83e7e99b78c82a2d1b6e0e40e94ad41af6b6d4ddab3fc01f10a57b806b97c9916e590c5dcc1da7748b4", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/45f8cda6bfc86f31da1455f0ba8d10ea9f2eaf0828a44e8778e8496cc45601315465deafeee285fe8ce6fd29ec015c3a5ec60679ca9b8b67174e60ac31348b6f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/e7edb29f509f3f89f009bfcc0b0c6f2475f43cc5375aa2d1b6909e3bb3a6a56141fb69a41a7413f4049320f507a7dade6975943b1c33ade7f25234f8cc651482"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-12 02:00:50.729323) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-12 02:00:50.729864) ========= 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=1f5564757af27664ca02d71f000001b0-1491986390.706528-1566323919 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004529 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-12 02:00:50.814967) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-12 02:00:50.815469) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-12 02:00:50.815962) ========= ========= Total master_lag: 0.19 =========