builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-037 starttime: 1493993974.37 results: success (0) revision: 9348b76977e833f108cf77dff75b0fab887a2fc1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-05 07:19:34.366718) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-05 07:19:34.367135) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-05 07:19:34.367416) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-05 07:19:34.382722) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 07:19:34.383073) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1493993891.533124-168417341 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-05-05 07:19:35-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 228M=0s 2017-05-05 07:19:35 (228 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.087491 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 07:19:34.485340) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 07:19:34.488736) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1493993891.533124-168417341 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018696 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 07:19:34.538054) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 07:19:34.541396) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 9348b76977e833f108cf77dff75b0fab887a2fc1 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 9348b76977e833f108cf77dff75b0fab887a2fc1 --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=56c518bb7cbc29fb3c9463e2000001c4-1493993891.533124-168417341 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-05-05 07:19:35,206 truncating revision to first 12 chars 2017-05-05 07:19:35,206 Setting DEBUG logging. 2017-05-05 07:19:35,206 attempt 1/10 2017-05-05 07:19:35,206 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/9348b76977e8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-05 07:19:35,477 unpacking tar archive at: mozilla-central-9348b76977e8/testing/mozharness/ program finished with exit code 0 elapsedTime=0.564684 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 07:19:35.123229) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-05 07:19:35.123620) ========= script_repo_revision: 9348b76977e833f108cf77dff75b0fab887a2fc1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-05 07:19:35.124095) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-05 07:19:35.124413) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-05 07:19:35.135342) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2017-05-05 07:19:35.135745) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=56c518bb7cbc29fb3c9463e2000001c4-1493993891.533124-168417341 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:19:35 INFO - MultiFileLogger online at 20170505 07:19:35 in /builds/slave/test 07:19:35 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 07:19:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:19:35 INFO - {'append_to_log': False, 07:19:35 INFO - 'base_work_dir': '/builds/slave/test', 07:19:35 INFO - 'blob_upload_branch': 'Firefox', 07:19:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:19:35 INFO - 'branch': 'Firefox', 07:19:35 INFO - 'buildbot_json_path': 'buildprops.json', 07:19:35 INFO - 'config_files': ('talos/linux_config.py',), 07:19:35 INFO - 'default_actions': ('clobber', 07:19:35 INFO - 'read-buildbot-config', 07:19:35 INFO - 'download-and-extract', 07:19:35 INFO - 'populate-webroot', 07:19:35 INFO - 'create-virtualenv', 07:19:35 INFO - 'install', 07:19:35 INFO - 'run-tests'), 07:19:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:19:35 INFO - 'download_minidump_stackwalk': True, 07:19:35 INFO - 'download_symbols': 'ondemand', 07:19:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:19:35 INFO - 'tooltool.py': '/tools/tooltool.py', 07:19:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:19:35 INFO - '/tools/misc-python/virtualenv.py')}, 07:19:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:19:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:19:35 INFO - 'gecko_profile': False, 07:19:35 INFO - 'gecko_profile_interval': 0, 07:19:35 INFO - 'installer_path': 'installer.exe', 07:19:35 INFO - 'log_level': 'info', 07:19:35 INFO - 'log_name': 'talos', 07:19:35 INFO - 'log_to_console': True, 07:19:35 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:19:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:19:35 INFO - 'opt_config_files': (), 07:19:35 INFO - 'pip_index': False, 07:19:35 INFO - 'suite': 'dromaeojs', 07:19:35 INFO - 'system_bits': '32', 07:19:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:19:35 INFO - 'title': 'talos-linux64-ix-037', 07:19:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:19:35 INFO - 'use_talos_json': True, 07:19:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:19:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:19:35 INFO - 'work_dir': 'build'} 07:19:35 INFO - [mozharness: 2017-05-05 14:19:35.897897Z] Running clobber step. 07:19:35 INFO - Running pre-action listener: _resource_record_pre_action 07:19:35 INFO - Running main action method: clobber 07:19:35 INFO - rmtree: /builds/slave/test/build 07:19:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:19:36 INFO - Running post-action listener: _resource_record_post_action 07:19:36 INFO - [mozharness: 2017-05-05 14:19:36.836589Z] Finished clobber step (success) 07:19:36 INFO - [mozharness: 2017-05-05 14:19:36.836689Z] Running read-buildbot-config step. 07:19:36 INFO - Running pre-action listener: _resource_record_pre_action 07:19:36 INFO - Running main action method: read_buildbot_config 07:19:36 INFO - Using buildbot properties: 07:19:36 INFO - { 07:19:36 INFO - "project": "", 07:19:36 INFO - "product": "firefox", 07:19:36 INFO - "who": "cbook@mozilla.com", 07:19:36 INFO - "installer_path": "public/build/target.tar.bz2", 07:19:36 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 07:19:36 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 07:19:36 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 07:19:36 INFO - "stage_platform": "linux64-pgo", 07:19:36 INFO - "basedir": "/builds/slave/test", 07:19:36 INFO - "buildnumber": 180, 07:19:36 INFO - "platform": "ubuntu64_hw", 07:19:36 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 07:19:36 INFO - "slavebuilddir": "test", 07:19:36 INFO - "taskId": "dVilUeCSR2-WJdh9mOduWw", 07:19:36 INFO - "branch": "mozilla-central", 07:19:36 INFO - "script_repo_revision": "production", 07:19:36 INFO - "revision": "9348b76977e833f108cf77dff75b0fab887a2fc1", 07:19:36 INFO - "slavename": "talos-linux64-ix-037", 07:19:36 INFO - "repo_path": "mozilla-central" 07:19:36 INFO - } 07:19:36 INFO - Finding installer, test and symbols from parent task. 07:19:36 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dVilUeCSR2-WJdh9mOduWw'}, attempt #1 07:19:37 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dVilUeCSR2-WJdh9mOduWw'}, attempt #1 07:19:37 INFO - Task dependencies: YwrTf5ZhTJmSd6M0Ez5Zog 07:19:37 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YwrTf5ZhTJmSd6M0Ez5Zog'}, attempt #1 07:19:38 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/YwrTf5ZhTJmSd6M0Ez5Zog/artifacts/public/build/target.tar.bz2 07:19:38 INFO - Set test_url: https://queue.taskcluster.net/v1/task/YwrTf5ZhTJmSd6M0Ez5Zog/artifacts/public/build/test_packages.json 07:19:38 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/YwrTf5ZhTJmSd6M0Ez5Zog/artifacts/public/build/target.crashreporter-symbols.zip 07:19:38 INFO - Running post-action listener: _resource_record_post_action 07:19:38 INFO - [mozharness: 2017-05-05 14:19:38.268471Z] Finished read-buildbot-config step (success) 07:19:38 INFO - [mozharness: 2017-05-05 14:19:38.268687Z] Running download-and-extract step. 07:19:38 INFO - Running pre-action listener: _resource_record_pre_action 07:19:38 INFO - Running main action method: download_and_extract 07:19:38 INFO - mkdir: /builds/slave/test/build/tests 07:19:38 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')]} 07:19:38 INFO - trying https://queue.taskcluster.net/v1/task/YwrTf5ZhTJmSd6M0Ez5Zog/artifacts/public/build/target.test_packages.json 07:19:38 INFO - Downloading https://queue.taskcluster.net/v1/task/YwrTf5ZhTJmSd6M0Ez5Zog/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 07:19:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YwrTf5ZhTJmSd6M0Ez5Zog/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 07:19:38 INFO - Downloaded 986 bytes. 07:19:38 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 07:19:38 INFO - Using the following test package requirements: 07:19:38 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 07:19:38 INFO - u'common': [u'target.common.tests.zip'], 07:19:38 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 07:19:38 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 07:19:38 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 07:19:38 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 07:19:38 INFO - u'mozbase': [u'target.common.tests.zip'], 07:19:38 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 07:19:38 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 07:19:38 INFO - u'web-platform': [u'target.common.tests.zip', 07:19:38 INFO - u'target.web-platform.tests.zip'], 07:19:38 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 07:19:38 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 07:19:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YwrTf5ZhTJmSd6M0Ez5Zog/artifacts/public/build/target.common.tests.zip 07:19:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YwrTf5ZhTJmSd6M0Ez5Zog/artifacts/public/build/target.common.tests.zip'}, attempt #1 07:19:38 INFO - Fetch https://queue.taskcluster.net/v1/task/YwrTf5ZhTJmSd6M0Ez5Zog/artifacts/public/build/target.common.tests.zip into memory 07:19:40 INFO - Content-Length response header: 60565137 07:19:40 INFO - Bytes received: 60565137 07:19:49 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 07:19:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YwrTf5ZhTJmSd6M0Ez5Zog/artifacts/public/build/target.common.tests.zip 07:19:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YwrTf5ZhTJmSd6M0Ez5Zog/artifacts/public/build/target.common.tests.zip'}, attempt #1 07:19:49 INFO - Fetch https://queue.taskcluster.net/v1/task/YwrTf5ZhTJmSd6M0Ez5Zog/artifacts/public/build/target.common.tests.zip into memory 07:19:51 INFO - Content-Length response header: 60565137 07:19:51 INFO - Bytes received: 60565137 07:20:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YwrTf5ZhTJmSd6M0Ez5Zog/artifacts/public/build/target.talos.tests.zip 07:20:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YwrTf5ZhTJmSd6M0Ez5Zog/artifacts/public/build/target.talos.tests.zip'}, attempt #1 07:20:01 INFO - Fetch https://queue.taskcluster.net/v1/task/YwrTf5ZhTJmSd6M0Ez5Zog/artifacts/public/build/target.talos.tests.zip into memory 07:20:02 INFO - Content-Length response header: 14408697 07:20:02 INFO - Bytes received: 14408697 07:20:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:20:02 INFO - trying https://queue.taskcluster.net/v1/task/YwrTf5ZhTJmSd6M0Ez5Zog/artifacts/public/build/target.tar.bz2 07:20:02 INFO - Downloading https://queue.taskcluster.net/v1/task/YwrTf5ZhTJmSd6M0Ez5Zog/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 07:20:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YwrTf5ZhTJmSd6M0Ez5Zog/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 07:20:04 INFO - Downloaded 64054691 bytes. 07:20:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YwrTf5ZhTJmSd6M0Ez5Zog/artifacts/public/build/target.tar.bz2 07:20:04 INFO - mkdir: /builds/slave/test/properties 07:20:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:20:04 INFO - Writing to file /builds/slave/test/properties/build_url 07:20:04 INFO - Contents: 07:20:04 INFO - build_url:https://queue.taskcluster.net/v1/task/YwrTf5ZhTJmSd6M0Ez5Zog/artifacts/public/build/target.tar.bz2 07:20:04 INFO - Running post-action listener: _resource_record_post_action 07:20:04 INFO - Running post-action listener: set_extra_try_arguments 07:20:04 INFO - [mozharness: 2017-05-05 14:20:04.070481Z] Finished download-and-extract step (success) 07:20:04 INFO - [mozharness: 2017-05-05 14:20:04.070578Z] Running populate-webroot step. 07:20:04 INFO - Running pre-action listener: _resource_record_pre_action 07:20:04 INFO - Running main action method: populate_webroot 07:20:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:20:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:20:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:20:04 INFO - '--tppagecycles', 07:20:04 INFO - '7'], 07:20:04 INFO - 'tests': ['tsvgm']}}, 07:20:04 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:20:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:20:04 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:20:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:20:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:20:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:20:04 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:20:04 INFO - 'talos_options': ['--disable-e10s'], 07:20:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:20:04 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:20:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:20:04 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:20:04 INFO - 'talos_options': ['--disable-e10s'], 07:20:04 INFO - 'tests': ['damp', 'tps']}, 07:20:04 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:20:04 INFO - 'tests': ['damp', 'tps']}, 07:20:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:20:04 INFO - 'tests': ['dromaeo_dom']}, 07:20:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:20:04 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:20:04 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 07:20:04 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 07:20:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:20:04 INFO - 'tests': ['a11yr', 07:20:04 INFO - 'ts_paint', 07:20:04 INFO - 'tpaint', 07:20:04 INFO - 'sessionrestore', 07:20:04 INFO - 'sessionrestore_no_auto_restore', 07:20:04 INFO - 'tabpaint']}, 07:20:04 INFO - 'other-e10s': {'tests': ['a11yr', 07:20:04 INFO - 'ts_paint', 07:20:04 INFO - 'tpaint', 07:20:04 INFO - 'sessionrestore', 07:20:04 INFO - 'sessionrestore_no_auto_restore', 07:20:04 INFO - 'tabpaint']}, 07:20:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:20:04 INFO - 'ts_paint', 07:20:04 INFO - 'tpaint', 07:20:04 INFO - 'sessionrestore', 07:20:04 INFO - 'sessionrestore_no_auto_restore', 07:20:04 INFO - 'tabpaint']}, 07:20:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:20:04 INFO - 'ts_paint', 07:20:04 INFO - 'tpaint', 07:20:04 INFO - 'sessionrestore', 07:20:04 INFO - 'sessionrestore_no_auto_restore', 07:20:04 INFO - 'tabpaint']}, 07:20:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:20:04 INFO - 'tests': ['a11yr', 07:20:04 INFO - 'ts_paint', 07:20:04 INFO - 'tpaint', 07:20:04 INFO - 'sessionrestore', 07:20:04 INFO - 'sessionrestore_no_auto_restore', 07:20:04 INFO - 'tabpaint']}, 07:20:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:20:04 INFO - 'tests': ['a11yr', 07:20:04 INFO - 'ts_paint', 07:20:04 INFO - 'tpaint', 07:20:04 INFO - 'sessionrestore', 07:20:04 INFO - 'sessionrestore_no_auto_restore', 07:20:04 INFO - 'tabpaint']}, 07:20:04 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 07:20:04 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 07:20:04 INFO - 'bloom_basic_ref']}, 07:20:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:20:04 INFO - 'tests': ['tsvgx', 07:20:04 INFO - 'tsvgr_opacity', 07:20:04 INFO - 'tart', 07:20:04 INFO - 'tscrollx', 07:20:04 INFO - 'cart', 07:20:04 INFO - 'tsvg_static']}, 07:20:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:20:04 INFO - 'tsvgr_opacity', 07:20:04 INFO - 'tart', 07:20:04 INFO - 'tscrollx', 07:20:04 INFO - 'cart', 07:20:04 INFO - 'tsvg_static']}, 07:20:04 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:20:04 INFO - 'talos_options': ['--disable-e10s'], 07:20:04 INFO - 'tests': ['tp5o']}, 07:20:04 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:20:04 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:20:04 INFO - 'talos_options': ['--disable-e10s', 07:20:04 INFO - '--xperf_path', 07:20:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:20:04 INFO - 'tests': ['tp5n']}, 07:20:04 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:20:04 INFO - 'talos_options': ['--xperf_path', 07:20:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:20:04 INFO - 'tests': ['tp5n']}}, 07:20:04 INFO - 'talos.zip': {'path': '', 07:20:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:20:04 INFO - Running post-action listener: _resource_record_post_action 07:20:04 INFO - [mozharness: 2017-05-05 14:20:04.084259Z] Finished populate-webroot step (success) 07:20:04 INFO - [mozharness: 2017-05-05 14:20:04.084348Z] Running create-virtualenv step. 07:20:04 INFO - Running pre-action listener: _resource_record_pre_action 07:20:04 INFO - Running main action method: create_virtualenv 07:20:04 INFO - Creating virtualenv /builds/slave/test/build/venv 07:20:04 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 07:20:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:20:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:20:04 INFO - Using real prefix '/usr' 07:20:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:20:04 INFO - Installing distribute.............................................................................................................................................................................................done. 07:20:06 INFO - Installing pip.................done. 07:20:06 INFO - Return code: 0 07:20:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:20:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:20:06 INFO - Reading from file tmpfile_stdout 07:20:06 INFO - Output received: 07:20:06 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) 07:20:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:20:06 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')]} 07:20:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:20:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:20:06 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')]} 07:20:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:20:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:20:06 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 0xbca1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc07a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe68310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe65400>, '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 0xe663b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe66840>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1493993891.533124-168417341', '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 07:20:06 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 07:20:06 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 07:20:06 INFO - Using env: {'DISPLAY': ':0', 07:20:06 INFO - 'HOME': '/home/cltbld', 07:20:06 INFO - 'LANG': 'en_US.UTF-8', 07:20:06 INFO - 'LANGUAGE': 'en_US:en', 07:20:06 INFO - 'LOGNAME': 'cltbld', 07:20:06 INFO - 'MAIL': '/var/mail/cltbld', 07:20:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:20:06 INFO - 'MOZ_NO_REMOTE': '1', 07:20:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:20:06 INFO - 'NO_EM_RESTART': '1', 07:20:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:20:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:20:06 INFO - 'PWD': '/builds/slave/test', 07:20:06 INFO - 'SHELL': '/bin/bash', 07:20:06 INFO - 'SHLVL': '1', 07:20:06 INFO - 'TERM': 'linux', 07:20:06 INFO - 'TMOUT': '86400', 07:20:06 INFO - 'USER': 'cltbld', 07:20:06 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1493993891.533124-168417341', 07:20:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:20:06 INFO - '_': '/tools/buildbot/bin/python'} 07:20:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:20:07 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 07:20:07 INFO - Cleaning up... 07:20:07 INFO - Return code: 0 07:20:07 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:20:07 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')]} 07:20:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:20:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:20:07 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')]} 07:20:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:20:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:20:07 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 0xbca1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc07a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe68310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe65400>, '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 0xe663b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe66840>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1493993891.533124-168417341', '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 07:20:07 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 07:20:07 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 07:20:07 INFO - Using env: {'DISPLAY': ':0', 07:20:07 INFO - 'HOME': '/home/cltbld', 07:20:07 INFO - 'LANG': 'en_US.UTF-8', 07:20:07 INFO - 'LANGUAGE': 'en_US:en', 07:20:07 INFO - 'LOGNAME': 'cltbld', 07:20:07 INFO - 'MAIL': '/var/mail/cltbld', 07:20:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:20:07 INFO - 'MOZ_NO_REMOTE': '1', 07:20:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:20:07 INFO - 'NO_EM_RESTART': '1', 07:20:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:20:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:20:07 INFO - 'PWD': '/builds/slave/test', 07:20:07 INFO - 'SHELL': '/bin/bash', 07:20:07 INFO - 'SHLVL': '1', 07:20:07 INFO - 'TERM': 'linux', 07:20:07 INFO - 'TMOUT': '86400', 07:20:07 INFO - 'USER': 'cltbld', 07:20:07 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1493993891.533124-168417341', 07:20:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:20:07 INFO - '_': '/tools/buildbot/bin/python'} 07:20:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:20:07 INFO - Downloading/unpacking psutil>=3.1.1 07:20:07 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 07:20:07 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 07:20:07 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 07:20:07 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 07:20:07 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 07:20:07 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 07:20:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:20:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:20:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:20:08 INFO - Installing collected packages: psutil 07:20:08 INFO - Running setup.py install for psutil 07:20:08 INFO - building 'psutil._psutil_linux' extension 07:20:08 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 07:20:08 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 07:20:08 INFO - building 'psutil._psutil_posix' extension 07:20:08 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 07:20:08 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 07:20:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:20:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:20:08 INFO - Successfully installed psutil 07:20:08 INFO - Cleaning up... 07:20:09 INFO - Return code: 0 07:20:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:20:09 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')]} 07:20:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:20:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:20:09 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')]} 07:20:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:20:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:20:09 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 0xbca1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc07a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe68310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe65400>, '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 0xe663b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe66840>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1493993891.533124-168417341', '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 07:20:09 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 07:20:09 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 07:20:09 INFO - Using env: {'DISPLAY': ':0', 07:20:09 INFO - 'HOME': '/home/cltbld', 07:20:09 INFO - 'LANG': 'en_US.UTF-8', 07:20:09 INFO - 'LANGUAGE': 'en_US:en', 07:20:09 INFO - 'LOGNAME': 'cltbld', 07:20:09 INFO - 'MAIL': '/var/mail/cltbld', 07:20:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:20:09 INFO - 'MOZ_NO_REMOTE': '1', 07:20:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:20:09 INFO - 'NO_EM_RESTART': '1', 07:20:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:20:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:20:09 INFO - 'PWD': '/builds/slave/test', 07:20:09 INFO - 'SHELL': '/bin/bash', 07:20:09 INFO - 'SHLVL': '1', 07:20:09 INFO - 'TERM': 'linux', 07:20:09 INFO - 'TMOUT': '86400', 07:20:09 INFO - 'USER': 'cltbld', 07:20:09 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1493993891.533124-168417341', 07:20:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:20:09 INFO - '_': '/tools/buildbot/bin/python'} 07:20:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:20:09 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:20:09 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 07:20:09 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 07:20:09 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 07:20:09 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 07:20:09 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 07:20:09 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 07:20:10 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:20:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:20:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:20:10 INFO - Installing collected packages: mozsystemmonitor 07:20:10 INFO - Running setup.py install for mozsystemmonitor 07:20:10 INFO - Successfully installed mozsystemmonitor 07:20:10 INFO - Cleaning up... 07:20:10 INFO - Return code: 0 07:20:10 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:20:10 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')]} 07:20:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:20:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:20:10 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')]} 07:20:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:20:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:20:10 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 0xbca1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc07a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe68310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe65400>, '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 0xe663b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe66840>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1493993891.533124-168417341', '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 07:20:10 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 07:20:10 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 07:20:10 INFO - Using env: {'DISPLAY': ':0', 07:20:10 INFO - 'HOME': '/home/cltbld', 07:20:10 INFO - 'LANG': 'en_US.UTF-8', 07:20:10 INFO - 'LANGUAGE': 'en_US:en', 07:20:10 INFO - 'LOGNAME': 'cltbld', 07:20:10 INFO - 'MAIL': '/var/mail/cltbld', 07:20:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:20:10 INFO - 'MOZ_NO_REMOTE': '1', 07:20:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:20:10 INFO - 'NO_EM_RESTART': '1', 07:20:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:20:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:20:10 INFO - 'PWD': '/builds/slave/test', 07:20:10 INFO - 'SHELL': '/bin/bash', 07:20:10 INFO - 'SHLVL': '1', 07:20:10 INFO - 'TERM': 'linux', 07:20:10 INFO - 'TMOUT': '86400', 07:20:10 INFO - 'USER': 'cltbld', 07:20:10 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1493993891.533124-168417341', 07:20:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:20:10 INFO - '_': '/tools/buildbot/bin/python'} 07:20:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:20:10 INFO - Downloading/unpacking jsonschema==2.5.1 07:20:10 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 07:20:10 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 07:20:10 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 07:20:10 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 07:20:10 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 07:20:10 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 07:20:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:20:12 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:20:12 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 07:20:12 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 07:20:12 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 07:20:12 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 07:20:12 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 07:20:12 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 07:20:12 INFO - Downloading functools32-3.2.3-2.tar.gz 07:20:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:20:12 INFO - warning: no files found matching '*.txt' 07:20:12 INFO - no previously-included directories found matching 'build' 07:20:12 INFO - no previously-included directories found matching 'dist' 07:20:12 INFO - no previously-included directories found matching '.git*' 07:20:12 INFO - Installing collected packages: jsonschema, functools32 07:20:13 INFO - Running setup.py install for functools32 07:20:13 INFO - warning: no files found matching '*.txt' 07:20:13 INFO - no previously-included directories found matching 'build' 07:20:13 INFO - no previously-included directories found matching 'dist' 07:20:13 INFO - no previously-included directories found matching '.git*' 07:20:13 INFO - Successfully installed jsonschema functools32 07:20:13 INFO - Cleaning up... 07:20:13 INFO - Return code: 0 07:20:13 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:20:13 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')]} 07:20:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:20:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:20:13 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')]} 07:20:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:20:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:20:13 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 0xbca1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc07a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe68310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe65400>, '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 0xe663b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe66840>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1493993891.533124-168417341', '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 07:20:13 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 07:20:13 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 07:20:13 INFO - Using env: {'DISPLAY': ':0', 07:20:13 INFO - 'HOME': '/home/cltbld', 07:20:13 INFO - 'LANG': 'en_US.UTF-8', 07:20:13 INFO - 'LANGUAGE': 'en_US:en', 07:20:13 INFO - 'LOGNAME': 'cltbld', 07:20:13 INFO - 'MAIL': '/var/mail/cltbld', 07:20:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:20:13 INFO - 'MOZ_NO_REMOTE': '1', 07:20:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:20:13 INFO - 'NO_EM_RESTART': '1', 07:20:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:20:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:20:13 INFO - 'PWD': '/builds/slave/test', 07:20:13 INFO - 'SHELL': '/bin/bash', 07:20:13 INFO - 'SHLVL': '1', 07:20:13 INFO - 'TERM': 'linux', 07:20:13 INFO - 'TMOUT': '86400', 07:20:13 INFO - 'USER': 'cltbld', 07:20:13 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1493993891.533124-168417341', 07:20:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:20:13 INFO - '_': '/tools/buildbot/bin/python'} 07:20:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:20:13 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:20:13 INFO - Cleaning up... 07:20:13 INFO - Return code: 0 07:20:13 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:20:13 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')]} 07:20:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:20:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:20:13 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')]} 07:20:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:20:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:20:13 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 0xbca1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc07a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe68310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe65400>, '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 0xe663b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe66840>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1493993891.533124-168417341', '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 07:20:13 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 07:20:13 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 07:20:13 INFO - Using env: {'DISPLAY': ':0', 07:20:13 INFO - 'HOME': '/home/cltbld', 07:20:13 INFO - 'LANG': 'en_US.UTF-8', 07:20:13 INFO - 'LANGUAGE': 'en_US:en', 07:20:13 INFO - 'LOGNAME': 'cltbld', 07:20:13 INFO - 'MAIL': '/var/mail/cltbld', 07:20:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:20:13 INFO - 'MOZ_NO_REMOTE': '1', 07:20:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:20:13 INFO - 'NO_EM_RESTART': '1', 07:20:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:20:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:20:13 INFO - 'PWD': '/builds/slave/test', 07:20:13 INFO - 'SHELL': '/bin/bash', 07:20:13 INFO - 'SHLVL': '1', 07:20:13 INFO - 'TERM': 'linux', 07:20:13 INFO - 'TMOUT': '86400', 07:20:13 INFO - 'USER': 'cltbld', 07:20:13 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1493993891.533124-168417341', 07:20:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:20:13 INFO - '_': '/tools/buildbot/bin/python'} 07:20:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:20:13 INFO - Downloading/unpacking blobuploader==1.2.4 07:20:13 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 07:20:13 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 07:20:13 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 07:20:13 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 07:20:13 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 07:20:13 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 07:20:14 INFO - Downloading blobuploader-1.2.4.tar.gz 07:20:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:20:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:20:14 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 07:20:14 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 07:20:14 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 07:20:14 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 07:20:14 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 07:20:14 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 07:20:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:20:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:20:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:20:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:20:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:20:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:20:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:20:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:20:15 INFO - Downloading docopt-0.6.1.tar.gz 07:20:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:20:15 INFO - Installing collected packages: blobuploader, requests, docopt 07:20:15 INFO - Running setup.py install for blobuploader 07:20:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:20:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:20:15 INFO - Running setup.py install for requests 07:20:15 INFO - Running setup.py install for docopt 07:20:15 INFO - Successfully installed blobuploader requests docopt 07:20:15 INFO - Cleaning up... 07:20:15 INFO - Return code: 0 07:20:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:20:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:20:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:20:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:20:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:20:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:20:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:20:16 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 0xbca1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc07a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe68310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe65400>, '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 0xe663b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe66840>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1493993891.533124-168417341', '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 07:20:16 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 07:20:16 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 07:20:16 INFO - Using env: {'DISPLAY': ':0', 07:20:16 INFO - 'HOME': '/home/cltbld', 07:20:16 INFO - 'LANG': 'en_US.UTF-8', 07:20:16 INFO - 'LANGUAGE': 'en_US:en', 07:20:16 INFO - 'LOGNAME': 'cltbld', 07:20:16 INFO - 'MAIL': '/var/mail/cltbld', 07:20:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:20:16 INFO - 'MOZ_NO_REMOTE': '1', 07:20:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:20:16 INFO - 'NO_EM_RESTART': '1', 07:20:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:20:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:20:16 INFO - 'PWD': '/builds/slave/test', 07:20:16 INFO - 'SHELL': '/bin/bash', 07:20:16 INFO - 'SHLVL': '1', 07:20:16 INFO - 'TERM': 'linux', 07:20:16 INFO - 'TMOUT': '86400', 07:20:16 INFO - 'USER': 'cltbld', 07:20:16 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1493993891.533124-168417341', 07:20:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:20:16 INFO - '_': '/tools/buildbot/bin/python'} 07:20:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:20:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:20:16 INFO - Running setup.py (path:/tmp/pip-jZwUne-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:20:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:20:16 INFO - Running setup.py (path:/tmp/pip-pbd4JH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:20:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:20:16 INFO - Running setup.py (path:/tmp/pip-bvkrsx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:20:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:20:16 INFO - Running setup.py (path:/tmp/pip-5hkQM7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:20:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:20:16 INFO - Running setup.py (path:/tmp/pip-SEPeMo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:20:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:20:16 INFO - Running setup.py (path:/tmp/pip-kqRTgR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:20:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:20:16 INFO - Running setup.py (path:/tmp/pip-8n8brh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:20:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:20:16 INFO - Running setup.py (path:/tmp/pip-n6vD4s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:20:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:20:16 INFO - Running setup.py (path:/tmp/pip-T1kiPG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:20:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:20:16 INFO - Running setup.py (path:/tmp/pip-vmQh8q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:20:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:20:16 INFO - Running setup.py (path:/tmp/pip-LTDDkc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:20:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:20:16 INFO - Running setup.py (path:/tmp/pip-S59PYS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:20:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:20:17 INFO - Running setup.py (path:/tmp/pip-bDzKDN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:20:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:20:17 INFO - Running setup.py (path:/tmp/pip-SzP89h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:20:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:20:17 INFO - Running setup.py (path:/tmp/pip-txAKbX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:20:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:20:17 INFO - Running setup.py (path:/tmp/pip-Zq1xFY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:20:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:20:17 INFO - Running setup.py (path:/tmp/pip-VrtgKf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:20:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:20:17 INFO - Running setup.py install for manifestparser 07:20:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:20:17 INFO - Running setup.py install for mozcrash 07:20:17 INFO - Running setup.py install for mozdebug 07:20:17 INFO - Running setup.py install for mozdevice 07:20:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:20:17 INFO - Running setup.py install for mozfile 07:20:17 INFO - Running setup.py install for mozhttpd 07:20:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:20:18 INFO - Running setup.py install for mozinfo 07:20:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:20:18 INFO - Running setup.py install for mozInstall 07:20:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:20:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:20:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:20:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:20:18 INFO - Running setup.py install for mozleak 07:20:18 INFO - Running setup.py install for mozlog 07:20:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:20:18 INFO - Running setup.py install for moznetwork 07:20:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:20:18 INFO - Running setup.py install for mozprocess 07:20:18 INFO - Running setup.py install for mozprofile 07:20:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:20:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:20:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:20:18 INFO - Running setup.py install for mozrunner 07:20:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:20:18 INFO - Running setup.py install for mozscreenshot 07:20:18 INFO - Running setup.py install for moztest 07:20:19 INFO - Running setup.py install for mozversion 07:20:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:20:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:20:19 INFO - Cleaning up... 07:20:19 INFO - Return code: 0 07:20:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:20: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')]} 07:20:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:20:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:20: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')]} 07:20:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:20:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:20:19 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 0xbca1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc07a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe68310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe65400>, '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 0xe663b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe66840>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1493993891.533124-168417341', '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 07:20:19 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 07:20:19 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 07:20:19 INFO - Using env: {'DISPLAY': ':0', 07:20:19 INFO - 'HOME': '/home/cltbld', 07:20:19 INFO - 'LANG': 'en_US.UTF-8', 07:20:19 INFO - 'LANGUAGE': 'en_US:en', 07:20:19 INFO - 'LOGNAME': 'cltbld', 07:20:19 INFO - 'MAIL': '/var/mail/cltbld', 07:20:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:20:19 INFO - 'MOZ_NO_REMOTE': '1', 07:20:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:20:19 INFO - 'NO_EM_RESTART': '1', 07:20:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:20:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:20:19 INFO - 'PWD': '/builds/slave/test', 07:20:19 INFO - 'SHELL': '/bin/bash', 07:20:19 INFO - 'SHLVL': '1', 07:20:19 INFO - 'TERM': 'linux', 07:20:19 INFO - 'TMOUT': '86400', 07:20:19 INFO - 'USER': 'cltbld', 07:20:19 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1493993891.533124-168417341', 07:20:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:20:19 INFO - '_': '/tools/buildbot/bin/python'} 07:20:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:20:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:20:19 INFO - Running setup.py (path:/tmp/pip-HpDG7n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:20:19 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)) 07:20:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:20:19 INFO - Running setup.py (path:/tmp/pip-zEeVuB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:20:19 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)) 07:20:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:20:19 INFO - Running setup.py (path:/tmp/pip-xTnlex-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:20:19 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)) 07:20:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:20:19 INFO - Running setup.py (path:/tmp/pip-Q5c6ln-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:20:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:20:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:20:19 INFO - Running setup.py (path:/tmp/pip-Z5g0Th-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:20:19 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)) 07:20:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:20:19 INFO - Running setup.py (path:/tmp/pip-yaif7b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:20:19 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)) 07:20:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:20:19 INFO - Running setup.py (path:/tmp/pip-o0ejBC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:20:19 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)) 07:20:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:20:19 INFO - Running setup.py (path:/tmp/pip-j5qRbQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:20:19 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)) 07:20:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:20:19 INFO - Running setup.py (path:/tmp/pip-SCLSfY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:20:20 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)) 07:20:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:20:20 INFO - Running setup.py (path:/tmp/pip-K2VAfH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:20:20 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)) 07:20:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:20:20 INFO - Running setup.py (path:/tmp/pip-7n2Ph2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:20:20 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)) 07:20:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:20:20 INFO - Running setup.py (path:/tmp/pip-6oA3Rk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:20:20 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)) 07:20:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:20:20 INFO - Running setup.py (path:/tmp/pip-mMbs4L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:20:20 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)) 07:20:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:20:20 INFO - Running setup.py (path:/tmp/pip-GJ3kKh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:20:20 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)) 07:20:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:20:20 INFO - Running setup.py (path:/tmp/pip-6zkScm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:20:20 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)) 07:20:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:20:20 INFO - Running setup.py (path:/tmp/pip-5u9Slx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:20:20 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)) 07:20:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:20:20 INFO - Running setup.py (path:/tmp/pip-aT8Tfg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:20:20 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)) 07:20:20 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)) 07:20:20 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)) 07:20:20 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)) 07:20:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:20:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:20:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:20:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:20:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:20:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:20:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:20:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:20:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:20:21 INFO - Downloading blessings-1.6.tar.gz 07:20:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:20:21 INFO - Installing collected packages: blessings 07:20:21 INFO - Running setup.py install for blessings 07:20:21 INFO - Successfully installed blessings 07:20:21 INFO - Cleaning up... 07:20:21 INFO - Return code: 0 07:20:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:20:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:20:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:20:22 INFO - Reading from file tmpfile_stdout 07:20:22 INFO - Current package versions: 07:20:22 INFO - argparse == 1.2.1 07:20:22 INFO - blessings == 1.6 07:20:22 INFO - blobuploader == 1.2.4 07:20:22 INFO - docopt == 0.6.1 07:20:22 INFO - functools32 == 3.2.3-2 07:20:22 INFO - jsonschema == 2.5.1 07:20:22 INFO - manifestparser == 1.1 07:20:22 INFO - mozInstall == 1.12 07:20:22 INFO - mozcrash == 1.0 07:20:22 INFO - mozdebug == 0.1 07:20:22 INFO - mozdevice == 0.50 07:20:22 INFO - mozfile == 1.2 07:20:22 INFO - mozhttpd == 0.7 07:20:22 INFO - mozinfo == 0.9 07:20:22 INFO - mozleak == 0.1 07:20:22 INFO - mozlog == 3.4 07:20:22 INFO - moznetwork == 0.27 07:20:22 INFO - mozprocess == 0.25 07:20:22 INFO - mozprofile == 0.28 07:20:22 INFO - mozrunner == 6.13 07:20:22 INFO - mozscreenshot == 0.1 07:20:22 INFO - mozsystemmonitor == 0.3 07:20:22 INFO - moztest == 0.8 07:20:22 INFO - mozversion == 1.4 07:20:22 INFO - psutil == 3.1.1 07:20:22 INFO - requests == 1.2.3 07:20:22 INFO - wsgiref == 0.1.2 07:20:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:20:22 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')]} 07:20:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:20:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:20:22 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')]} 07:20:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:20:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:20:22 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 0xbca1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc07a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe68310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe65400>, '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 0xe663b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe66840>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1493993891.533124-168417341', '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 07:20:22 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 07:20:22 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 07:20:22 INFO - Using env: {'DISPLAY': ':0', 07:20:22 INFO - 'HOME': '/home/cltbld', 07:20:22 INFO - 'LANG': 'en_US.UTF-8', 07:20:22 INFO - 'LANGUAGE': 'en_US:en', 07:20:22 INFO - 'LOGNAME': 'cltbld', 07:20:22 INFO - 'MAIL': '/var/mail/cltbld', 07:20:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:20:22 INFO - 'MOZ_NO_REMOTE': '1', 07:20:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:20:22 INFO - 'NO_EM_RESTART': '1', 07:20:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:20:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:20:22 INFO - 'PWD': '/builds/slave/test', 07:20:22 INFO - 'SHELL': '/bin/bash', 07:20:22 INFO - 'SHLVL': '1', 07:20:22 INFO - 'TERM': 'linux', 07:20:22 INFO - 'TMOUT': '86400', 07:20:22 INFO - 'USER': 'cltbld', 07:20:22 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1493993891.533124-168417341', 07:20:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:20:22 INFO - '_': '/tools/buildbot/bin/python'} 07:20:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:20:22 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)) 07:20:22 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)) 07:20:22 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)) 07:20:22 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)) 07:20:22 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)) 07:20:22 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)) 07:20:22 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)) 07:20:22 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)) 07:20:22 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)) 07:20:22 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)) 07:20:22 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)) 07:20:22 INFO - Cleaning up... 07:20:22 INFO - Return code: 0 07:20:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:20:22 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')]} 07:20:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:20:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:20:22 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')]} 07:20:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:20:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:20:22 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 0xbca1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc07a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe68310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe65400>, '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 0xe663b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe66840>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1493993891.533124-168417341', '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 07:20:22 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 07:20:22 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 07:20:22 INFO - Using env: {'DISPLAY': ':0', 07:20:22 INFO - 'HOME': '/home/cltbld', 07:20:22 INFO - 'LANG': 'en_US.UTF-8', 07:20:22 INFO - 'LANGUAGE': 'en_US:en', 07:20:22 INFO - 'LOGNAME': 'cltbld', 07:20:22 INFO - 'MAIL': '/var/mail/cltbld', 07:20:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:20:22 INFO - 'MOZ_NO_REMOTE': '1', 07:20:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:20:22 INFO - 'NO_EM_RESTART': '1', 07:20:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:20:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:20:22 INFO - 'PWD': '/builds/slave/test', 07:20:22 INFO - 'SHELL': '/bin/bash', 07:20:22 INFO - 'SHLVL': '1', 07:20:22 INFO - 'TERM': 'linux', 07:20:22 INFO - 'TMOUT': '86400', 07:20:22 INFO - 'USER': 'cltbld', 07:20:22 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1493993891.533124-168417341', 07:20:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:20:22 INFO - '_': '/tools/buildbot/bin/python'} 07:20:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:20:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:20:22 INFO - Cleaning up... 07:20:22 INFO - Return code: 0 07:20:22 INFO - Running post-action listener: _resource_record_post_action 07:20:22 INFO - Running post-action listener: _start_resource_monitoring 07:20:22 INFO - Starting resource monitoring. 07:20:22 INFO - [mozharness: 2017-05-05 14:20:22.638027Z] Finished create-virtualenv step (success) 07:20:22 INFO - [mozharness: 2017-05-05 14:20:22.638493Z] Running install step. 07:20:22 INFO - Running pre-action listener: _resource_record_pre_action 07:20:22 INFO - Running main action method: install 07:20:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:20:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:20:22 INFO - Reading from file tmpfile_stdout 07:20:22 INFO - Detecting whether we're running mozinstall >=1.0... 07:20:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:20:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:20:22 INFO - Reading from file tmpfile_stdout 07:20:22 INFO - Output received: 07:20:22 INFO - Usage: mozinstall [options] installer 07:20:22 INFO - Options: 07:20:22 INFO - -h, --help show this help message and exit 07:20:22 INFO - -d DEST, --destination=DEST 07:20:22 INFO - Directory to install application into. [default: 07:20:22 INFO - "/builds/slave/test"] 07:20:22 INFO - --app=APP Application being installed. [default: firefox] 07:20:22 INFO - mkdir: /builds/slave/test/build/application 07:20:22 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'] 07:20:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 07:20:39 INFO - Reading from file tmpfile_stdout 07:20:39 INFO - Output received: 07:20:39 INFO - /builds/slave/test/build/application/firefox/firefox 07:20:39 INFO - Running post-action listener: _resource_record_post_action 07:20:39 INFO - [mozharness: 2017-05-05 14:20:39.096170Z] Finished install step (success) 07:20:39 INFO - [mozharness: 2017-05-05 14:20:39.096508Z] Running run-tests step. 07:20:39 INFO - Running pre-action listener: _resource_record_pre_action 07:20:39 INFO - Running main action method: run_tests 07:20:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:20:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:20:39 INFO - Python 2.7.3 07:20:39 INFO - Return code: 0 07:20:39 INFO - grabbing minidump binary from tooltool 07:20: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')]} 07:20:39 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 0xe65400>, '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 0xe663b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe66840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:20:39 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 07:20:39 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 07:20:39 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 07:20:39 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:20:39 INFO - Return code: 0 07:20:39 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:20:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:20:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:20:39 INFO - ENV: RUST_BACKTRACE is now 1 07:20:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:20:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:20:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YwrTf5ZhTJmSd6M0Ez5Zog/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--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 07:20:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/YwrTf5ZhTJmSd6M0Ez5Zog/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-037 --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 07:20:39 INFO - Using env: {'DISPLAY': ':0', 07:20:39 INFO - 'HOME': '/home/cltbld', 07:20:39 INFO - 'LANG': 'en_US.UTF-8', 07:20:39 INFO - 'LANGUAGE': 'en_US:en', 07:20:39 INFO - 'LOGNAME': 'cltbld', 07:20:39 INFO - 'MAIL': '/var/mail/cltbld', 07:20:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:20:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:20:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:20:39 INFO - 'MOZ_NO_REMOTE': '1', 07:20:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:20:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:20:39 INFO - 'NO_EM_RESTART': '1', 07:20:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:20:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:20:39 INFO - 'PWD': '/builds/slave/test', 07:20:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:20:39 INFO - 'RUST_BACKTRACE': '1', 07:20:39 INFO - 'SHELL': '/bin/bash', 07:20:39 INFO - 'SHLVL': '1', 07:20:39 INFO - 'TERM': 'linux', 07:20:39 INFO - 'TMOUT': '86400', 07:20:39 INFO - 'USER': 'cltbld', 07:20:39 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1493993891.533124-168417341', 07:20:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:20:39 INFO - '_': '/tools/buildbot/bin/python'} 07:20:39 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YwrTf5ZhTJmSd6M0Ez5Zog/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--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 07:20:39 INFO - mozversion application_buildid: 20170505131750 07:20:39 INFO - mozversion application_changeset: 9348b76977e833f108cf77dff75b0fab887a2fc1 07:20:39 INFO - mozversion application_display_name: Nightly 07:20:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:20:39 INFO - mozversion application_name: Firefox 07:20:39 INFO - mozversion application_remotingname: firefox 07:20:39 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:20:39 INFO - mozversion application_vendor: Mozilla 07:20:39 INFO - mozversion application_version: 55.0a1 07:20:39 INFO - mozversion platform_buildid: 20170505131750 07:20:39 INFO - mozversion platform_changeset: 9348b76977e833f108cf77dff75b0fab887a2fc1 07:20:39 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:20:39 INFO - mozversion platform_version: 55.0a1 07:20:39 INFO - using testdate: 1493994039 07:20:39 INFO - actual date: 1493994039 07:20:39 INFO - starting webserver on 'localhost:53911' 07:20:39 INFO - SUITE-START | Running 2 tests 07:20:39 INFO - TEST-START | dromaeo_css 07:20:39 INFO - operating with platform_type : linux_ 07:20:39 INFO - Initialising browser for dromaeo_css test... 07:20:39 INFO - TEST-INFO | started process 2367 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpaTgHXX/profile http://localhost:53911/getInfo.html) 07:20:43 INFO - PID 2367 | __metrics Screen width/height:1600/1200 07:20:43 INFO - PID 2367 | colorDepth:24 07:20:43 INFO - PID 2367 | Browser inner width/height: 1024/695 07:20:43 INFO - PID 2367 | __metrics 07:20:43 INFO - PID 2367 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:20:43 INFO - PID 2367 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:20:44 INFO - TEST-INFO | 2367: exit 0 07:20:44 INFO - Browser initialized. 07:20:44 INFO - Running cycle 1/1 for dromaeo_css test... 07:20:44 INFO - TEST-INFO | started process 2534 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpaTgHXX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:20:56 INFO - PID 2534 | RSS: Main: 149561344 07:20:56 INFO - PID 2534 | 07:23:59 INFO - PID 2534 | Cycle 1(1): loaded http://localhost:53911/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53911/tests/dromaeo/cssquery-ext.html) 07:24:01 INFO - PID 2534 | RSS: Main: 315609088 07:24:01 INFO - PID 2534 | 07:27:03 INFO - PID 2534 | Cycle 1(1): loaded http://localhost:53911/tests/dromaeo/cssquery-ext.html (next: http://localhost:53911/tests/dromaeo/cssquery-jquery.html) 07:27:03 INFO - PID 2534 | RSS: Main: 231317504 07:27:03 INFO - PID 2534 | 07:30:05 INFO - PID 2534 | Cycle 1(1): loaded http://localhost:53911/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53911/tests/dromaeo/cssquery-mootools.html) 07:30:06 INFO - PID 2534 | RSS: Main: 237109248 07:30:06 INFO - PID 2534 | 07:33:07 INFO - PID 2534 | Cycle 1(1): loaded http://localhost:53911/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53911/tests/dromaeo/cssquery-prototype.html) 07:33:08 INFO - PID 2534 | RSS: Main: 173957120 07:33:08 INFO - PID 2534 | 07:36:11 INFO - PID 2534 | Cycle 1(1): loaded http://localhost:53911/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53911/tests/dromaeo/cssquery-yui.html) 07:36:11 INFO - PID 2534 | RSS: Main: 176574464 07:36:11 INFO - PID 2534 | 07:39:13 INFO - PID 2534 | Cycle 1(1): loaded http://localhost:53911/tests/dromaeo/cssquery-yui.html (next: http://localhost:53911/tests/dromaeo/cssquery-dojo.html) 07:39:13 INFO - PID 2534 | RSS: Main: 270675968 07:39:13 INFO - PID 2534 | 07:39:13 INFO - PID 2534 | __start_tp_report 07:39:13 INFO - PID 2534 | _x_x_mozilla_page_load 07:39:13 INFO - PID 2534 | _x_x_mozilla_page_load_details 07:39:13 INFO - PID 2534 | |i|pagename|runs| 07:39:13 INFO - PID 2534 | |0;dojo.html;3362.83185840708;3377.3414184234084;3389.864405423784;3390.6948374646277;3426.8800591979284;5820.417958204177;5916.290045194577;5920.111983202511;5963;5996.520278377725;5943.851403714909;5947.910781338283;5957.88084238315;6011.18848955392;6038.909416358758;4825.396825396825;5146.74266286686;5228.111221092414;5285.207218917155;5287.762050707708;5287.68273903567;5297.682139071649;5345.492178243061;5369.221462887879;5380.112281473552;5142.665726727751;5197.272381866542;5246.186841039645;5285.7885684572575;5320.095583750772;5201.141811601081;5203.19350500673;5214.113600687854;5248.239005344242;5308.389535203462;5243.030039442715;5250.68495890244;5253.080710875582;5263.078961181816;5295.7087360195555;5767.192593036978;5813.47678708914;5842.620229685057;5843.590948633598;5893.528517718615;6461.709223084926;6464.547481676284;6465.450436712885;6467.029945508211;6469.191351081115;6516.511261655395;6518.804435866884;6523.249826269945;6523.706433210469;6525.0212468129685;5831.591788574801;5851.327097383855;5895.4399332063385;5919.763209471573;6058.727357268934;6423.5503514754455;6484.1894763154605;6514.185726784152;6587.901181482282;6597.8350541235895;6166.845828854314;6177.289611694712;6232.56372053961;6242.531810114259;6344.302126766052;6408.10286559882;6459.773907913201;6480.967595161994;6482.189726284215;6486.643234622141;6152.261728592597;6229.4393504584805;6431.228252609717;6438.45273151778;6438.484921206292;6214.502839772807;6307.5584709070845;6336.398042185985;6349.333320001426;6359.682015899187;4933;4943.950560494421;4944;4945.455999840015;4976.278439626234;4255;4354.978225108917;4359;4381.846635367747;4398.56014398564;6003.219581454383;6043;6068.939310606926;6077.848053798691;6095.512359011268;6388.680565971683;6430.517711171681;6435.324290949477;6440.935590644127;6441.71012304438;5947.940520594739;6008.759649614054;6012.7895523657335;6016.819495415145;6018.518518518508;5529.778808847601;5559.972200139055;5574.749136288877;5583.497485226349;5598.244237027949;6548.672566371758;6625.17185351831;6684.465242780566;6691.631960242136;6697.162854643072;6491.837704057436;6509.056186852975;6513.674316284072;6513.902291465726;6527.771527996498;6730;6787.4230690389895;6787.592744435349;6792.388685018372;6799.422049125879;6217.378262173746;6395.392437718409;6396.58422202574;6404.871902562016;6413.198350206224;6320.399562041599;6353.396427339396;6400.199975003125;6483.870322593616;6496.707648155939;6123.496151642784;6199.194104766351;6199.938000619936;6202.441780239619;6234.937650623617;6273.121762953099;6273.121762953099;6278.843028924313;6281.96859015702;6298.653574053471;6255.655938923403;6279.089532017741;6280.5289603279025;6283.5601507895835;6288.622682639057;6117.035835319669;6153.2000839890525;6214.471769899609;6246.781362652286;6311.431971122621;6123.679189354865;6151.661658608819;6159.815205543661;6164;6164.106204600397 07:39:13 INFO - PID 2534 | |1;ext.html;652.5758257132866;714.1144980224522;738.4092725819346;1143.571160814694;1171.970700732482;28894;36526.63473365265;37515;37531.4370284446;37532.81233593835;38600.22799544007;38972;39022;39064.21871562567;39087;5620.353659329168;5726.856828579298;7137.036500072476;7270.672819723099;7274.818129546751;6998.405135563483;7145.821354466128;7147.785566433015;7148.642567871611;7222.4583156263225;7241.855162896739;7322.524035937647;7323.414126869838;7324.63376831159;7326.047613810224;7231;7290.198078211366;7294.051773269441;7298.9635051824935;7304.196538380774;7013.123359580052;7058.647067646649;7059;7059.435245180373;7066.540674856169;34897.127571810655;34997.47503787445;35045.47431788525;35056;35071.824640876635;3513.6486351364915;3545.3972824619877;3546.343926373603;3550.715942724576;3551.200979779555;27764.722352776414;27825.16524504268;27876.303092422648;27876.860615696995;27901;3790.298794722985;3810.9809450952566;3812.046988252937;3823.025128592219;3826.7512611680154;192309;193504;194014;194330.99999999857;194375;48139;48182;48235.5176448231;48291;48383;49508.75245623749;49906;49958.50041499538;49991.25013124733;50018;15176.544703658908;15261.923690381476;15274.31265593051;15278.770818437732;15283.847161528465;10915.290506117077;11107.111431085494;12449.128561000643;12650.746985060432;12665.556705515262;12006.339651319262;12112.69718257033;12135.87864121365;12155.878441215475;12286.201396909173;4734.313524538922;4758.762061896891;4775.21209000511;4777.498362671939;4788.712677239377;1063.31416236527;1080.9405482698528;1085.8859819718973;1111.994440027811;1137.1869113583828;25450.618240726402;25773;25975.350616234744;26063.609045864327;26147.869260653573;1181.1259667845732;1183.8930599888959;1209.455744914792;1210.1589395370133;1215.4348228073989;12916;13527.526536571173;13562.050656453954;13608.863911360957;13620.727585448236;227179;227892;228365;228410;228927;50401;50406.747966259936;50444.74777626088;50481.24278135831;50541.24188137181;1469.9118052916858;1520.2474774986451;1534.823495298055;1536.4084827341553;1539.6150962259435;23819.880900595384;24870.626940595543;25282.024881167657;28698;30491;48195.5180448191;48630.513694864014;48639.513604863496;48644;48903.75548122237;191382;191431;192314;192842;193164;30231.58147348163;31282.843585781924;31571.684283156876;31725;31725.36549269048;40106;41747.582524174366;41789.58210417857;41801.372979404725;41838.581614183466;29895.850520747255;30526.694733052384;30571.847140764155;30999.690003100583;32260.516092258167;31017.8449107753;31099.533506997865;31514;31522.68477315289;32383.51424728584;35936.28127437489;35984;36184.09539761527;36280.4557931626;36301.45547816732;28652.713472865005;29917.551236731902;30576;30697.846510767304;31575.21061973469;24520.509589808462;24840;25013.74986250187;25091.49817003613;25185.874070630263 07:39:13 INFO - PID 2534 | |2;jquery.html;375.1211836291269;428.4686988134712;448.51784331843254;456.1743244727044;463.7124982510843;19798.60402791943;20629.484262893435;20970.37088887332;21269.468263293424;21412.25057122997;22724.65913011306;22843.200487982926;22917.885410572922;22943.541129177407;23582.882085589546;5458.863528411782;5516.255305533762;5517.586181036419;5572.247746554204;5650.5479561634975;3439.4152993990956;3585.103724068983;5521.641093328921;5596.188552659883;5596.636218645795;3560.3413368526903;3572.374834403982;5372.597055220855;5455.781768729246;5531.2532808071;5386.218998245272;5483.396826349099;5514.035043867328;5521.199426083235;5523.447655234485;5023.397192336943;5026.623003274769;5026.723530205837;5028.220625803006;5033.446320904697;26129.73870261311;26211.344716382053;26436.603450948252;26470.07354742594;26951.595726064126;2113.7357830271217;2171.6416791229426;2173.1741938063433;2173.413178441812;2173.467500462381;16312.429064982787;17042;17058.829411705847;17215.245560710227;17531;2136.380449669594;2163.729533808274;2201.0315461197183;2203.030666506748;2219.178903805602;108658.91132572434;115539.4223028888;117216;117990;118423;11184.328940263611;11370.260933039326;12532.87467125317;12544.560940367044;12592.181508201938;17358.65282694339;17441;17561.824381756018;17568.56078598045;17719.911400443172;9129.589168487433;9195.862062069074;9277.072292770668;9289.396189247676;9456.196223321094;8587.871181932276;8602.913970860336;8831.55842207887;8934.73195804127;8935.731928042169;7842.5686587237105;7896.368290536743;7973;8086.838263234702;8134.75595732129;2140.6987488449977;2178.6470611739283;2205.283282933053;2234.4078819112947;2299.195281651442;1020.6121673763922;1045.7803861189084;1050.695298363466;1051.7686109055996;1054.6835949215206;18916.810831891504;20030.298939537337;20164.69752953707;20221.191152354037;20532.486687832923;1057.7522764126231;1065.5471424644495;1065.6217042949797;1074.9892501075046;1078.8058149533003;10948.343099413902;10953.123750100132;11052.281601696024;11105.555777768799;11165.385903775208;470377;473709;477398;482145;484120;96564;96573;97167.51416242874;99323.50338248406;103194;1155.1451925575182;1194.116353898109;1216.4890745886748;1221.865594784573;1230.366361323919;16836;16912.74630880513;17166.141692915055;17326;17828.3760068397;42585.574144258164;43376.34935475907;43657.781711091244;44092.11815763731;44424.77787611041;53141.468585313654;55757;55860.16209756776;56780.71609641925;56932.715336423054;9213.76965575866;9273.582688778886;9337.953310233406;9356.625734970525;9474.857877131986;14272.785908211592;14435.422583096979;14470.782938255725;14477;14972.925135374253;11611.767764644828;13013.869861301266;13047.608571742863;13132.540361087316;13291.136076155019;12341.938290308492;12447.751044978868;12546.49814007466;12619.179753316004;12808.935955320163;13388.263645499592;13446.596602101952;13524.459021639024;13925.79111313311;13945.930270348583;9304.395214311047;9367.812643746951;9431.151196392357;9765.951170244387;9829.803403931739;12536.185147965352;12737.490500380252;12755.362231888554;12898.419571119135;13267.203967761965 07:39:13 INFO - PID 2534 | |3;mootools.html;1327.1373607155347;1383.1286289637526;1386.6394737368284;1387.4519564771915;1408.2958520739637;10842.132629389649;11220.887791122086;11237.269577477458;11253.718657033569;11266.155038372113;11043.83434248485;11082.390468524229;11099.889001109987;11181.888181118187;11193.16051296152;2348.80035197008;2383.8688872112025;2385.0936644075314;2390.6175011998;2391.2228525729206;2036.0735865181382;2315.073970411832;2376.0495801679294;2384.5350156719446;2392.091005417939;2049.497873021112;2238.7649296823843;2363.279199844047;2364.5625559271502;2370.395549134968;2312.838101332907;2334.2297041976108;2342.074880422232;2354.0466111224896;2354.505553833697;2318.5594736999915;2342.3909783456425;2344.2264052862515;2345.812335013195;2361.645753137026;11270;11347.319160850375;11356.148288878367;11360.204785665006;11360.318380897173;6387.840303992391;6576.441002514792;6583.835404114935;6603.537752357337;6608.074869518271;3665.871694490707;3840.577536470986;4047.97976010121;4057.8173982170874;4102.425660407545;1343.9865601344056;1372.2726954714017;1413.6465883529117;1420.0911416693325;1420.6235347632885;17448.738268925976;17546.824531754773;17605.383811566535;17644.823551764446;17651.646967060715;5203.9219411708855;5209.869753256199;5343.679379237258;5473.7263136843;5527.336719593594;12272.938635306766;12311.19977201497;12347.19743216688;12348.506059757688;12348.567800126952;6394.616323020634;6428.903566446507;6429.903551446732;6435.22777266731;6441.259255185713;5946.316173640001;6014.759409623566;6103.542234332443;6118.60229085108;6165.445109940127;4220.810063547147;4222.239996800606;4266.5946735060115;4272.957270427318;4346.695731298842;1084.7017070305728;1229.661842993184;1231.0643910627987;1260.7541529401678;1267.3029833591488;758.1887380502919;878.1613559051091;904.653141654344;920.663957655451;928.4986107501874;6493.51298652603;6514.413702766774;6568.967155164193;6572.671366431659;6673.332666733288;967.3712087143413;970.5098925042809;971.5288085278635;979.456401697065;989.1098011789303;4052.9392059118545;4146.212219678312;4165.271077561415;4175.144095460423;4198.538160802309;15209.923950380176;15366.846331536764;15507.689846203013;15534.84465155334;15628.140452275235;14841.257937103102;15420.614484637974;15424.074555526704;15434.459793907385;15451.768223476656;9201.861972070425;9309.208717258973;9346.439213647067;9376.578053987587;9418.811623767486;6623.569467984566;6961.547499412522;7135.8572828544175;7217.7112915484895;7272.490925635367;13349.466021359036;13500.392482338515;13509.72980540364;13768.311584420939;13783;13814.171149731048;13850.30748462593;13874.861251387762;13984.930075349557;14300.642483937569;9271.86092208604;9498.382605130644;9513;9518.904810952079;9642.951785241028;11121.16591255643;11166.776664466503;11190.496427660937;11198.160137989846;11231.269967452416;9232.676856309998;9384.859227111463;9388.295877809054;9399.060093990547;9534.570944307661;9110.590023449093;9173.541322933694;9262.073792620684;9362.157405833508;9553;7565.886511702219;7749.535027898119;7784.260495252942;7786.454948153575;7787.571683557232;6263.812085637438;6276.717547710456;6318.684065796784;6321.557490975588;6511.511636627177;5993.880122397615;6147.815565533041;6149.477294430024;6227.470664993526;6293.276273228455 07:39:13 INFO - PID 2534 | |4;prototype.html;418.20749679357607;419.9496060472743;422.35590724145675;422.57531181162926;425.5552947170207;5746.798862039829;5771.595988280822;5807.41925807419;5837.270341207349;5837.445442682938;5664.858378540539;5715.256846661767;5745.563609097726;5749.195112684228;5783.479486846183;4266.424032755569;4356.106998065389;4380.2115619188535;4392.582704643054;4554.407926969498;4367.803448844794;4381.75900325482;4382.255016647162;4386.850443282784;4397.50484835156;4317.266064768997;4353.41228934093;4357.455318085239;4358.411614432059;4368.082702632451;4294.677899157576;4297.247981603223;4311.676624253194;4337.306031035035;4352.847650332224;3810.1998580298173;3811.714121440903;3811.8475260989526;3814.2371525694693;3822.73240873139;5608.46719561641;5611.523020543218;5621.915671264935;5641.323041235078;5647.491725744661;4905.754712264408;5036.11867923112;5109.897802043938;5126.948730512684;5166.715830629314;6047.879042419127;6063.575549711522;6072.817815465543;6074.787382441594;6110.450059494622;4284.678649101299;4304.720193187432;4306.224879521685;4307.375430562551;4316.115196384733;6029;6180.907286390708;6247.00191877199;6388.137601423841;6444.066779966039;4249.192653395907;4322.579355560896;4366.890827729332;4374.256376416017;4391.560843915583;4416.3596278539435;4421.491528474202;4439.911201775946;4441.666874984389;4453.220686379936;4918.426103646871;5065.39215294167;5096.286519887219;5100.081985242692;5105.948940510547;6103.908441373383;6104.877902441926;6112.938870611237;6114.785982490592;6138.943453942979;1516.8255650600101;1517.855803698647;1518.4305885293015;1520.5818399939883;1522.14759734549;1011.812814629296;1028.6965345223177;1038.924712922122;1046.5238316565944;1047.9004494573003;5869.155703014532;5873.4126587340925;5883.970580147072;5889.38161493037;5935.73289201987;6190.0714892765545;6254.812355629248;6258.874822503524;6263.71813268404;6263.71813268404;5685.459881311269;5759.107338362561;5793.275840519935;5802.535797136304;5916.313850552572;3304.545999760098;3312.1222875937897;3312.635610082889;3313.618933822593;3317.457382317234;6463.192100987377;6524.151860258136;6529.347065293433;6596.571222870594;6599.571027883172;6418.614883107029;6436.227652681709;6449.3873082057125;6452.806415807533;6456.386643268882;6256.279523131085;6419.2296924369375;6481.902771458432;6483.286838447767;6494.123293355526;6232.657203853651;6243.875122497434;6264.655443950626;6271.867565027913;6282.560220784684;6151.200343955257;6172.197614310111;6213.2233470816145;6223.937760622517;6250.531210159347;6516.315786842313;6542.803715888341;6546.705398257185;6553.246376666744;6561.967190164019;4372.409724687126;4388.9122217556105;4393;4398.934015989698;4407.206702793528;6073.696315184311;6221.5333849960525;6274.874502510015;6275.246970363585;6305.337939516285;6068.2692788244585;6068.666223357758;6079.817605471843;6081.939180608314;6087.8782424350375;5875.265591801025;6098.451139397298;6110.205673262448;6111.358307377839;6114.816555503342;6112.2970858351855;6121.540884433596;6133;6137.3248942617165;6150.815475535741;5961.701914904151;6149.493374123368;6150.7847225346895;6163.660998645118;6177.887980163614;5958;5981.790637327673;5999.4900433463645;6022.307434644897;6026.427489388501 07:39:13 INFO - PID 2534 | |5;yui.html;860.5955201055501;878.8769572259885;885.7254251182138;885.8538341173706;901.387056801375;14669.486567970123;15377;15383.461578844743;15384.384624615002;15392.076475411483;16465.753013704772;17175.398861039866;17237.22432490538;17275.568110797238;17287.74068388975;993.5926270229207;1019.4240254256322;1019.8623185869886;1022.3405903192437;1023.0179028133001;986.659602437158;1020.7856350166473;1029.4698230411343;1030.345730461155;1033.8604288421043;1082.3505896462138;1085.7122862441458;1092.2954694222221;1092.464692300775;1093.8031154392206;1078.0728573426848;1081.2431298091367;1082.0586090101583;1083.7669900971289;1086.8858769829174;794.8052727081844;796.8087658961865;798.0981490915286;798.2376830127273;799.1329407592768;15587.454439094578;15601.687966240612;16154.596135096599;18003.639927201515;18231.72652410215;19087.713684294748;19088.33190838314;19381.418557443158;19395.709064364044;19580.608387832304;14577;14666.046706964013;14762.114273143647;14802.555923322318;14850.702985940221;3418.162550175223;3438.381091403546;3454.827258637083;3464.2898205867737;3464.3071385722706;87149.56425217833;88871.55564222203;89503;90109;91615;14300.856991429953;14598.854011459962;14730.705385892223;14731.779023314659;14799.186044767426;25387;25850.095246666275;27108.32229194286;27196.59205111925;27321.590176147372;8357.498550087013;8432.831343373098;8516.914830851736;8526.31789456842;8564.785880352916;3860;3941.921161576752;3946.1910308387287;3949.940750888739;3961.1087505311652;4501.707389019703;4516.683832131785;4545.727256364628;4554.726716397026;4586.220342541776;910.1808372464834;927.5067141901533;934.5700977550267;939.2439086535404;941.5057095025167;676.8037269191977;685.4961603221628;686.4165459359605;689.2556039477353;754.3927138653437;11720.472578733978;12142.45358958849;12468.251904885736;12499.687507812196;12520.81218781719;677.3023785500942;711.2034521336136;740.9184989651134;741.6032422653855;765.6171914042978;5377.655586103474;5748.827535173951;5823.621464604787;5845.064789633639;5876.970615146897;80326;80556.7359753624;81557;83995.5800220995;84561;73655.63172184105;74315;75272;76351;77832.61083694658;64515.67742161259;65314;66164.66917665477;66692.33307666861;66968;11191.888081119085;11591.594294199662;11635.069194464253;11651.53393864236;11791.88208117908;33304.666953330154;33521.32957340791;34096.31807363888;34221.48667769936;35232.471512926815;41128;41424.1715165701;42101.78949105235;42821;43519.56480435282;15022.248887555797;15209.771853421986;15332.770008450105;15474.613134671723;15602.921985390001;16646.25091870893;16941;16999.91500042492;17003.40488082911;17004.319827206775;15967.8403215971;15980.920095399448;16140.435084772447;16630.584235394213;16658.916705416395;15557.76663350028;15607.375704972128;15640.2961866714;15851.603709907344;16100.436484722979;15564.066156030674;15741.44904928322;15829.050256984618;15895.046297222203;15950.441734539236;10405.167586592903;10597.47012649349;10836.078933290759;10957.287776294515;11101.833472498081;11638.068954483451;11909.880901190878;13719.176849389069;13900.791488127485;14211.107612807986 07:39:13 INFO - PID 2534 | __end_tp_report 07:39:13 INFO - PID 2534 | __start_cc_report 07:39:13 INFO - PID 2534 | _x_x_mozilla_cycle_collect,1630 07:39:13 INFO - PID 2534 | __end_cc_report 07:39:13 INFO - PID 2534 | __startTimestamp1493995153620__endTimestamp 07:39:13 INFO - PID 2534 | 07:39:13 INFO - PID 2534 | ------- Summary: start ------- 07:39:13 INFO - PID 2534 | Number of tests: 6 07:39:13 INFO - PID 2534 | 07:39:13 INFO - PID 2534 | [#0] dojo.html Cycles:165 Average:5918.17 Median:6164.00 stddev:717.83 (11.6%) stddev-sans-first:691.48 07:39:13 INFO - PID 2534 | Values: 3362.8 3377.3 3389.9 3390.7 3426.9 5820.4 5916.3 5920.1 5963.0 5996.5 5943.9 5947.9 5957.9 6011.2 6038.9 4825.4 5146.7 5228.1 5285.2 5287.8 5287.7 5297.7 5345.5 5369.2 5380.1 5142.7 5197.3 5246.2 5285.8 5320.1 5201.1 5203.2 5214.1 5248.2 5308.4 5243.0 5250.7 5253.1 5263.1 5295.7 5767.2 5813.5 5842.6 5843.6 5893.5 6461.7 6464.5 6465.5 6467.0 6469.2 6516.5 6518.8 6523.2 6523.7 6525.0 5831.6 5851.3 5895.4 5919.8 6058.7 6423.6 6484.2 6514.2 6587.9 6597.8 6166.8 6177.3 6232.6 6242.5 6344.3 6408.1 6459.8 6481.0 6482.2 6486.6 6152.3 6229.4 6431.2 6438.5 6438.5 6214.5 6307.6 6336.4 6349.3 6359.7 4933.0 4944.0 4944.0 4945.5 4976.3 4255.0 4355.0 4359.0 4381.8 4398.6 6003.2 6043.0 6068.9 6077.8 6095.5 6388.7 6430.5 6435.3 6440.9 6441.7 5947.9 6008.8 6012.8 6016.8 6018.5 5529.8 5560.0 5574.7 5583.5 5598.2 6548.7 6625.2 6684.5 6691.6 6697.2 6491.8 6509.1 6513.7 6513.9 6527.8 6730.0 6787.4 6787.6 6792.4 6799.4 6217.4 6395.4 6396.6 6404.9 6413.2 6320.4 6353.4 6400.2 6483.9 6496.7 6123.5 6199.2 6199.9 6202.4 6234.9 6273.1 6273.1 6278.8 6282.0 6298.7 6255.7 6279.1 6280.5 6283.6 6288.6 6117.0 6153.2 6214.5 6246.8 6311.4 6123.7 6151.7 6159.8 6164.0 6164.1 07:39:13 INFO - PID 2534 | 07:39:13 INFO - PID 2534 | [#1] ext.html Cycles:180 Average:37018.59 Median:31992.94 stddev:53273.83 (166.5%) stddev-sans-first:53353.29 07:39:13 INFO - PID 2534 | Values: 652.6 714.1 738.4 1143.6 1172.0 28894.0 36526.6 37515.0 37531.4 37532.8 38600.2 38972.0 39022.0 39064.2 39087.0 5620.4 5726.9 7137.0 7270.7 7274.8 6998.4 7145.8 7147.8 7148.6 7222.5 7241.9 7322.5 7323.4 7324.6 7326.0 7231.0 7290.2 7294.1 7299.0 7304.2 7013.1 7058.6 7059.0 7059.4 7066.5 34897.1 34997.5 35045.5 35056.0 35071.8 3513.6 3545.4 3546.3 3550.7 3551.2 27764.7 27825.2 27876.3 27876.9 27901.0 3790.3 3811.0 3812.0 3823.0 3826.8 192309.0 193504.0 194014.0 194331.0 194375.0 48139.0 48182.0 48235.5 48291.0 48383.0 49508.8 49906.0 49958.5 49991.3 50018.0 15176.5 15261.9 15274.3 15278.8 15283.8 10915.3 11107.1 12449.1 12650.7 12665.6 12006.3 12112.7 12135.9 12155.9 12286.2 4734.3 4758.8 4775.2 4777.5 4788.7 1063.3 1080.9 1085.9 1112.0 1137.2 25450.6 25773.0 25975.4 26063.6 26147.9 1181.1 1183.9 1209.5 1210.2 1215.4 12916.0 13527.5 13562.1 13608.9 13620.7 227179.0 227892.0 228365.0 228410.0 228927.0 50401.0 50406.7 50444.7 50481.2 50541.2 1469.9 1520.2 1534.8 1536.4 1539.6 23819.9 24870.6 25282.0 28698.0 30491.0 48195.5 48630.5 48639.5 48644.0 48903.8 191382.0 191431.0 192314.0 192842.0 193164.0 30231.6 31282.8 31571.7 31725.0 31725.4 40106.0 41747.6 41789.6 41801.4 41838.6 29895.9 30526.7 30571.8 30999.7 32260.5 31017.8 31099.5 31514.0 31522.7 32383.5 35936.3 35984.0 36184.1 36280.5 36301.5 28652.7 29917.6 30576.0 30697.8 31575.2 24520.5 24840.0 25013.7 25091.5 25185.9 07:39:13 INFO - PID 2534 | 07:39:13 INFO - PID 2534 | [#2] jquery.html Cycles:180 Average:30644.42 Median:2202.03 stddev:79634.83 (3616.4%) stddev-sans-first:79825.80 07:39:13 INFO - PID 2534 | Values: 375.1 428.5 448.5 456.2 463.7 19798.6 20629.5 20970.4 21269.5 21412.3 22724.7 22843.2 22917.9 22943.5 23582.9 5458.9 5516.3 5517.6 5572.2 5650.5 3439.4 3585.1 5521.6 5596.2 5596.6 3560.3 3572.4 5372.6 5455.8 5531.3 5386.2 5483.4 5514.0 5521.2 5523.4 5023.4 5026.6 5026.7 5028.2 5033.4 26129.7 26211.3 26436.6 26470.1 26951.6 2113.7 2171.6 2173.2 2173.4 2173.5 16312.4 17042.0 17058.8 17215.2 17531.0 2136.4 2163.7 2201.0 2203.0 2219.2 108658.9 115539.4 117216.0 117990.0 118423.0 11184.3 11370.3 12532.9 12544.6 12592.2 17358.7 17441.0 17561.8 17568.6 17719.9 9129.6 9195.9 9277.1 9289.4 9456.2 8587.9 8602.9 8831.6 8934.7 8935.7 7842.6 7896.4 7973.0 8086.8 8134.8 2140.7 2178.6 2205.3 2234.4 2299.2 1020.6 1045.8 1050.7 1051.8 1054.7 18916.8 20030.3 20164.7 20221.2 20532.5 1057.8 1065.5 1065.6 1075.0 1078.8 10948.3 10953.1 11052.3 11105.6 11165.4 470377.0 473709.0 477398.0 482145.0 484120.0 96564.0 96573.0 97167.5 99323.5 103194.0 1155.1 1194.1 1216.5 1221.9 1230.4 16836.0 16912.7 17166.1 17326.0 17828.4 42585.6 43376.3 43657.8 44092.1 44424.8 53141.5 55757.0 55860.2 56780.7 56932.7 9213.8 9273.6 9338.0 9356.6 9474.9 14272.8 14435.4 14470.8 14477.0 14972.9 11611.8 13013.9 13047.6 13132.5 13291.1 12341.9 12447.8 12546.5 12619.2 12808.9 13388.3 13446.6 13524.5 13925.8 13945.9 9304.4 9367.8 9431.2 9766.0 9829.8 12536.2 12737.5 12755.4 12898.4 13267.2 07:39:13 INFO - PID 2534 | 07:39:13 INFO - PID 2534 | [#3] mootools.html Cycles:180 Average:7183.33 Median:3753.22 stddev:4654.01 (124.0%) stddev-sans-first:4646.26 07:39:13 INFO - PID 2534 | Values: 1327.1 1383.1 1386.6 1387.5 1408.3 10842.1 11220.9 11237.3 11253.7 11266.2 11043.8 11082.4 11099.9 11181.9 11193.2 2348.8 2383.9 2385.1 2390.6 2391.2 2036.1 2315.1 2376.0 2384.5 2392.1 2049.5 2238.8 2363.3 2364.6 2370.4 2312.8 2334.2 2342.1 2354.0 2354.5 2318.6 2342.4 2344.2 2345.8 2361.6 11270.0 11347.3 11356.1 11360.2 11360.3 6387.8 6576.4 6583.8 6603.5 6608.1 3665.9 3840.6 4048.0 4057.8 4102.4 1344.0 1372.3 1413.6 1420.1 1420.6 17448.7 17546.8 17605.4 17644.8 17651.6 5203.9 5209.9 5343.7 5473.7 5527.3 12272.9 12311.2 12347.2 12348.5 12348.6 6394.6 6428.9 6429.9 6435.2 6441.3 5946.3 6014.8 6103.5 6118.6 6165.4 4220.8 4222.2 4266.6 4273.0 4346.7 1084.7 1229.7 1231.1 1260.8 1267.3 758.2 878.2 904.7 920.7 928.5 6493.5 6514.4 6569.0 6572.7 6673.3 967.4 970.5 971.5 979.5 989.1 4052.9 4146.2 4165.3 4175.1 4198.5 15209.9 15366.8 15507.7 15534.8 15628.1 14841.3 15420.6 15424.1 15434.5 15451.8 9201.9 9309.2 9346.4 9376.6 9418.8 6623.6 6961.5 7135.9 7217.7 7272.5 13349.5 13500.4 13509.7 13768.3 13783.0 13814.2 13850.3 13874.9 13984.9 14300.6 9271.9 9498.4 9513.0 9518.9 9643.0 11121.2 11166.8 11190.5 11198.2 11231.3 9232.7 9384.9 9388.3 9399.1 9534.6 9110.6 9173.5 9262.1 9362.2 9553.0 7565.9 7749.5 7784.3 7786.5 7787.6 6263.8 6276.7 6318.7 6321.6 6511.5 5993.9 6147.8 6149.5 6227.5 6293.3 07:39:13 INFO - PID 2534 | 07:39:13 INFO - PID 2534 | [#4] prototype.html Cycles:180 Average:5103.10 Median:5804.98 stddev:1529.82 (26.4%) stddev-sans-first:1493.15 07:39:13 INFO - PID 2534 | Values: 418.2 419.9 422.4 422.6 425.6 5746.8 5771.6 5807.4 5837.3 5837.4 5664.9 5715.3 5745.6 5749.2 5783.5 4266.4 4356.1 4380.2 4392.6 4554.4 4367.8 4381.8 4382.3 4386.9 4397.5 4317.3 4353.4 4357.5 4358.4 4368.1 4294.7 4297.2 4311.7 4337.3 4352.8 3810.2 3811.7 3811.8 3814.2 3822.7 5608.5 5611.5 5621.9 5641.3 5647.5 4905.8 5036.1 5109.9 5126.9 5166.7 6047.9 6063.6 6072.8 6074.8 6110.5 4284.7 4304.7 4306.2 4307.4 4316.1 6029.0 6180.9 6247.0 6388.1 6444.1 4249.2 4322.6 4366.9 4374.3 4391.6 4416.4 4421.5 4439.9 4441.7 4453.2 4918.4 5065.4 5096.3 5100.1 5105.9 6103.9 6104.9 6112.9 6114.8 6138.9 1516.8 1517.9 1518.4 1520.6 1522.1 1011.8 1028.7 1038.9 1046.5 1047.9 5869.2 5873.4 5884.0 5889.4 5935.7 6190.1 6254.8 6258.9 6263.7 6263.7 5685.5 5759.1 5793.3 5802.5 5916.3 3304.5 3312.1 3312.6 3313.6 3317.5 6463.2 6524.2 6529.3 6596.6 6599.6 6418.6 6436.2 6449.4 6452.8 6456.4 6256.3 6419.2 6481.9 6483.3 6494.1 6232.7 6243.9 6264.7 6271.9 6282.6 6151.2 6172.2 6213.2 6223.9 6250.5 6516.3 6542.8 6546.7 6553.2 6562.0 4372.4 4388.9 4393.0 4398.9 4407.2 6073.7 6221.5 6274.9 6275.2 6305.3 6068.3 6068.7 6079.8 6081.9 6087.9 5875.3 6098.5 6110.2 6111.4 6114.8 6112.3 6121.5 6133.0 6137.3 6150.8 5961.7 6149.5 6150.8 6163.7 6177.9 5958.0 5981.8 5999.5 6022.3 6026.4 07:39:13 INFO - PID 2534 | 07:39:13 INFO - PID 2534 | [#5] yui.html Cycles:180 Average:18771.55 Median:19388.56 stddev:23280.13 (120.1%) stddev-sans-first:23306.58 07:39:13 INFO - PID 2534 | Values: 860.6 878.9 885.7 885.9 901.4 14669.5 15377.0 15383.5 15384.4 15392.1 16465.8 17175.4 17237.2 17275.6 17287.7 993.6 1019.4 1019.9 1022.3 1023.0 986.7 1020.8 1029.5 1030.3 1033.9 1082.4 1085.7 1092.3 1092.5 1093.8 1078.1 1081.2 1082.1 1083.8 1086.9 794.8 796.8 798.1 798.2 799.1 15587.5 15601.7 16154.6 18003.6 18231.7 19087.7 19088.3 19381.4 19395.7 19580.6 14577.0 14666.0 14762.1 14802.6 14850.7 3418.2 3438.4 3454.8 3464.3 3464.3 87149.6 88871.6 89503.0 90109.0 91615.0 14300.9 14598.9 14730.7 14731.8 14799.2 25387.0 25850.1 27108.3 27196.6 27321.6 8357.5 8432.8 8516.9 8526.3 8564.8 3860.0 3941.9 3946.2 3949.9 3961.1 4501.7 4516.7 4545.7 4554.7 4586.2 910.2 927.5 934.6 939.2 941.5 676.8 685.5 686.4 689.3 754.4 11720.5 12142.5 12468.3 12499.7 12520.8 677.3 711.2 740.9 741.6 765.6 5377.7 5748.8 5823.6 5845.1 5877.0 80326.0 80556.7 81557.0 83995.6 84561.0 73655.6 74315.0 75272.0 76351.0 77832.6 64515.7 65314.0 66164.7 66692.3 66968.0 11191.9 11591.6 11635.1 11651.5 11791.9 33304.7 33521.3 34096.3 34221.5 35232.5 41128.0 41424.2 42101.8 42821.0 43519.6 15022.2 15209.8 15332.8 15474.6 15602.9 16646.3 16941.0 16999.9 17003.4 17004.3 15967.8 15980.9 16140.4 16630.6 16658.9 15557.8 15607.4 15640.3 15851.6 16100.4 15564.1 15741.4 15829.1 15895.0 15950.4 10405.2 10597.5 10836.1 10957.3 11101.8 11638.1 11909.9 13719.2 13900.8 14211.1 07:39:13 INFO - PID 2534 | -------- Summary: end -------- 07:39:13 INFO - PID 2534 | 07:39:14 INFO - TEST-INFO | 2534: exit 0 07:39:14 INFO - TEST-OK | dromaeo_css | took 1115066ms 07:39:14 INFO - TEST-START | kraken 07:39:14 INFO - operating with platform_type : linux_ 07:39:14 INFO - Initialising browser for kraken test... 07:39:14 INFO - TEST-INFO | started process 3029 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpC_K9bk/profile http://localhost:53911/getInfo.html) 07:39:18 INFO - PID 3029 | __metrics Screen width/height:1600/1200 07:39:18 INFO - PID 3029 | colorDepth:24 07:39:18 INFO - PID 3029 | Browser inner width/height: 1024/695 07:39:18 INFO - PID 3029 | __metrics 07:39:18 INFO - PID 3029 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:39:18 INFO - PID 3029 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:39:19 INFO - TEST-INFO | 3029: exit 0 07:39:19 INFO - Browser initialized. 07:39:19 INFO - Running cycle 1/1 for kraken test... 07:39:19 INFO - TEST-INFO | started process 3124 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpC_K9bk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:39:31 INFO - PID 3124 | RSS: Main: 149729280 07:39:31 INFO - PID 3124 | 07:39:34 INFO - PID 3124 | Cycle 1(1): loaded http://localhost:53911/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53911/tests/kraken/driver.html?testName=audio-beat-detection) 07:39:35 INFO - PID 3124 | RSS: Main: 222490624 07:39:35 INFO - PID 3124 | 07:39:37 INFO - PID 3124 | Cycle 1(1): loaded http://localhost:53911/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53911/tests/kraken/driver.html?testName=audio-dft) 07:39:38 INFO - PID 3124 | RSS: Main: 183681024 07:39:38 INFO - PID 3124 | 07:39:40 INFO - PID 3124 | Cycle 1(1): loaded http://localhost:53911/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53911/tests/kraken/driver.html?testName=audio-fft) 07:39:41 INFO - PID 3124 | RSS: Main: 264019968 07:39:41 INFO - PID 3124 | 07:39:43 INFO - PID 3124 | Cycle 1(1): loaded http://localhost:53911/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53911/tests/kraken/driver.html?testName=audio-oscillator) 07:39:44 INFO - PID 3124 | RSS: Main: 175030272 07:39:44 INFO - PID 3124 | 07:39:46 INFO - PID 3124 | Cycle 1(1): loaded http://localhost:53911/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53911/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:39:46 INFO - PID 3124 | RSS: Main: 177967104 07:39:46 INFO - PID 3124 | 07:39:49 INFO - PID 3124 | Cycle 1(1): loaded http://localhost:53911/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53911/tests/kraken/driver.html?testName=imaging-darkroom) 07:39:50 INFO - PID 3124 | RSS: Main: 234958848 07:39:50 INFO - PID 3124 | 07:39:53 INFO - PID 3124 | Cycle 1(1): loaded http://localhost:53911/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53911/tests/kraken/driver.html?testName=imaging-desaturate) 07:39:54 INFO - PID 3124 | RSS: Main: 257376256 07:39:54 INFO - PID 3124 | 07:39:56 INFO - PID 3124 | Cycle 1(1): loaded http://localhost:53911/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53911/tests/kraken/driver.html?testName=json-parse-financial) 07:39:57 INFO - PID 3124 | RSS: Main: 275378176 07:39:57 INFO - PID 3124 | 07:39:59 INFO - PID 3124 | Cycle 1(1): loaded http://localhost:53911/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53911/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:39:59 INFO - PID 3124 | RSS: Main: 202498048 07:39:59 INFO - PID 3124 | 07:40:01 INFO - PID 3124 | Cycle 1(1): loaded http://localhost:53911/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53911/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:40:01 INFO - PID 3124 | RSS: Main: 208207872 07:40:01 INFO - PID 3124 | 07:40:03 INFO - PID 3124 | Cycle 1(1): loaded http://localhost:53911/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53911/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:40:04 INFO - PID 3124 | RSS: Main: 218050560 07:40:04 INFO - PID 3124 | 07:40:07 INFO - PID 3124 | Cycle 1(1): loaded http://localhost:53911/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53911/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:40:07 INFO - PID 3124 | RSS: Main: 226242560 07:40:07 INFO - PID 3124 | 07:40:10 INFO - PID 3124 | Cycle 1(1): loaded http://localhost:53911/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53911/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:40:11 INFO - PID 3124 | RSS: Main: 221102080 07:40:11 INFO - PID 3124 | 07:40:12 INFO - PID 3124 | Cycle 1(1): loaded http://localhost:53911/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53911/tests/kraken/driver.html?testName=ai-astar) 07:40:13 INFO - PID 3124 | RSS: Main: 271740928 07:40:13 INFO - PID 3124 | 07:40:13 INFO - PID 3124 | __start_tp_report 07:40:13 INFO - PID 3124 | _x_x_mozilla_page_load 07:40:13 INFO - PID 3124 | _x_x_mozilla_page_load_details 07:40:13 INFO - PID 3124 | |i|pagename|runs| 07:40:13 INFO - PID 3124 | |0;ai-astar;107;111;109;108;110;108;108;108;109;109 07:40:13 INFO - PID 3124 | |1;audio-beat-detection;137;115;121;153;118;117;121;122;139;117 07:40:13 INFO - PID 3124 | |2;audio-dft;161;160;160;160;161;161;160;160;161;161 07:40:13 INFO - PID 3124 | |3;audio-fft;74;75;73;111;74;74;74;74;102;73 07:40:13 INFO - PID 3124 | |4;audio-oscillator;83;83;105;83;82;83;95;82;83;108 07:40:13 INFO - PID 3124 | |5;imaging-gaussian-blur;114;113;114;113;113;113;113;113;113;113 07:40:13 INFO - PID 3124 | |6;imaging-darkroom;148;149;147;146;146;146;146;147;146;147 07:40:13 INFO - PID 3124 | |7;imaging-desaturate;94;95;95;94;94;94;94;94;94;94 07:40:13 INFO - PID 3124 | |8;json-parse-financial;44;46;47;45;45;45;46;46;45;45 07:40:13 INFO - PID 3124 | |9;json-stringify-tinderbox;58;55;56;56;57;55;56;56;56;55 07:40:13 INFO - PID 3124 | |10;stanford-crypto-aes;80;80;80;82;80;80;80;80;80;80 07:40:13 INFO - PID 3124 | |11;stanford-crypto-ccm;130;134;124;127;125;136;130;124;168;123 07:40:13 INFO - PID 3124 | |12;stanford-crypto-pbkdf2;193;173;180;200;180;181;176;183;179;177 07:40:13 INFO - PID 3124 | |13;stanford-crypto-sha256-iterative;68;65;65;65;64;66;65;63;63;65 07:40:13 INFO - PID 3124 | __end_tp_report 07:40:13 INFO - PID 3124 | __start_cc_report 07:40:13 INFO - PID 3124 | _x_x_mozilla_cycle_collect,1664 07:40:13 INFO - PID 3124 | __end_cc_report 07:40:13 INFO - PID 3124 | __startTimestamp1493995213215__endTimestamp 07:40:13 INFO - PID 3124 | 07:40:13 INFO - PID 3124 | ------- Summary: start ------- 07:40:13 INFO - PID 3124 | Number of tests: 14 07:40:13 INFO - PID 3124 | 07:40:13 INFO - PID 3124 | [#0] ai-astar Cycles:10 Average:108.70 Median:109.00 stddev:1.16 (1.1%) stddev-sans-first:1.05 07:40:13 INFO - PID 3124 | Values: 107.0 111.0 109.0 108.0 110.0 108.0 108.0 108.0 109.0 109.0 07:40:13 INFO - PID 3124 | 07:40:13 INFO - PID 3124 | [#1] audio-beat-detection Cycles:10 Average:126.00 Median:121.50 stddev:12.61 (10.4%) stddev-sans-first:12.74 07:40:13 INFO - PID 3124 | Values: 137.0 115.0 121.0 153.0 118.0 117.0 121.0 122.0 139.0 117.0 07:40:13 INFO - PID 3124 | 07:40:13 INFO - PID 3124 | [#2] audio-dft Cycles:10 Average:160.50 Median:161.00 stddev:0.53 (0.3%) stddev-sans-first:0.53 07:40:13 INFO - PID 3124 | Values: 161.0 160.0 160.0 160.0 161.0 161.0 160.0 160.0 161.0 161.0 07:40:13 INFO - PID 3124 | 07:40:13 INFO - PID 3124 | [#3] audio-fft Cycles:10 Average:80.40 Median:74.00 stddev:13.93 (18.8%) stddev-sans-first:14.58 07:40:13 INFO - PID 3124 | Values: 74.0 75.0 73.0 111.0 74.0 74.0 74.0 74.0 102.0 73.0 07:40:13 INFO - PID 3124 | 07:40:13 INFO - PID 3124 | [#4] audio-oscillator Cycles:10 Average:88.70 Median:83.00 stddev:10.17 (12.2%) stddev-sans-first:10.57 07:40:13 INFO - PID 3124 | Values: 83.0 83.0 105.0 83.0 82.0 83.0 95.0 82.0 83.0 108.0 07:40:13 INFO - PID 3124 | 07:40:13 INFO - PID 3124 | [#5] imaging-gaussian-blur Cycles:10 Average:113.20 Median:113.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 07:40:13 INFO - PID 3124 | Values: 114.0 113.0 114.0 113.0 113.0 113.0 113.0 113.0 113.0 113.0 07:40:13 INFO - PID 3124 | 07:40:13 INFO - PID 3124 | [#6] imaging-darkroom Cycles:10 Average:146.80 Median:147.00 stddev:1.03 (0.7%) stddev-sans-first:1.00 07:40:13 INFO - PID 3124 | Values: 148.0 149.0 147.0 146.0 146.0 146.0 146.0 147.0 146.0 147.0 07:40:13 INFO - PID 3124 | 07:40:13 INFO - PID 3124 | [#7] imaging-desaturate Cycles:10 Average:94.20 Median:94.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 07:40:13 INFO - PID 3124 | Values: 94.0 95.0 95.0 94.0 94.0 94.0 94.0 94.0 94.0 94.0 07:40:13 INFO - PID 3124 | 07:40:13 INFO - PID 3124 | [#8] json-parse-financial Cycles:10 Average:45.40 Median:45.50 stddev:0.84 (1.9%) stddev-sans-first:0.73 07:40:13 INFO - PID 3124 | Values: 44.0 46.0 47.0 45.0 45.0 45.0 46.0 46.0 45.0 45.0 07:40:13 INFO - PID 3124 | 07:40:13 INFO - PID 3124 | [#9] json-stringify-tinderbox Cycles:10 Average:56.00 Median:56.00 stddev:0.94 (1.7%) stddev-sans-first:0.67 07:40:13 INFO - PID 3124 | Values: 58.0 55.0 56.0 56.0 57.0 55.0 56.0 56.0 56.0 55.0 07:40:13 INFO - PID 3124 | 07:40:13 INFO - PID 3124 | [#10] stanford-crypto-aes Cycles:10 Average:80.20 Median:80.00 stddev:0.63 (0.8%) stddev-sans-first:0.67 07:40:13 INFO - PID 3124 | Values: 80.0 80.0 80.0 82.0 80.0 80.0 80.0 80.0 80.0 80.0 07:40:13 INFO - PID 3124 | 07:40:13 INFO - PID 3124 | [#11] stanford-crypto-ccm Cycles:10 Average:132.10 Median:130.00 stddev:13.36 (10.3%) stddev-sans-first:14.15 07:40:13 INFO - PID 3124 | Values: 130.0 134.0 124.0 127.0 125.0 136.0 130.0 124.0 168.0 123.0 07:40:13 INFO - PID 3124 | 07:40:13 INFO - PID 3124 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.20 Median:180.50 stddev:8.20 (4.5%) stddev-sans-first:7.71 07:40:13 INFO - PID 3124 | Values: 193.0 173.0 180.0 200.0 180.0 181.0 176.0 183.0 179.0 177.0 07:40:13 INFO - PID 3124 | 07:40:13 INFO - PID 3124 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.90 Median:65.00 stddev:1.45 (2.2%) stddev-sans-first:1.01 07:40:13 INFO - PID 3124 | Values: 68.0 65.0 65.0 65.0 64.0 66.0 65.0 63.0 63.0 65.0 07:40:13 INFO - PID 3124 | -------- Summary: end -------- 07:40:13 INFO - PID 3124 | 07:40:14 INFO - TEST-INFO | 3124: exit 0 07:40:14 INFO - TEST-OK | kraken | took 59983ms 07:40:14 INFO - SUITE-END | took 1175s 07:40:14 INFO - Completed test suite (00:19:35) 07:40:14 INFO - javascript benchmark 07:40:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7646.0097905820085, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3362.83185840708, 3377.3414184234084, 3389.864405423784, 3390.6948374646277, 3426.8800591979284, 5820.417958204177, 5916.290045194577, 5920.111983202511, 5963.0, 5996.520278377725, 5943.851403714909, 5947.910781338283, 5957.88084238315, 6011.18848955392, 6038.909416358758, 4825.396825396825, 5146.74266286686, 5228.111221092414, 5285.207218917155, 5287.762050707708, 5287.68273903567, 5297.682139071649, 5345.492178243061, 5369.221462887879, 5380.112281473552, 5142.665726727751, 5197.272381866542, 5246.186841039645, 5285.7885684572575, 5320.095583750772, 5201.141811601081, 5203.19350500673, 5214.113600687854, 5248.239005344242, 5308.389535203462, 5243.030039442715, 5250.68495890244, 5253.080710875582, 5263.078961181816, 5295.7087360195555, 5767.192593036978, 5813.47678708914, 5842.620229685057, 5843.590948633598, 5893.528517718615, 6461.709223084926, 6464.547481676284, 6465.450436712885, 6467.029945508211, 6469.191351081115, 6516.511261655395, 6518.804435866884, 6523.249826269945, 6523.706433210469, 6525.0212468129685, 5831.591788574801, 5851.327097383855, 5895.4399332063385, 5919.763209471573, 6058.727357268934, 6423.5503514754455, 6484.1894763154605, 6514.185726784152, 6587.901181482282, 6597.8350541235895, 6166.845828854314, 6177.289611694712, 6232.56372053961, 6242.531810114259, 6344.302126766052, 6408.10286559882, 6459.773907913201, 6480.967595161994, 6482.189726284215, 6486.643234622141, 6152.261728592597, 6229.4393504584805, 6431.228252609717, 6438.45273151778, 6438.484921206292, 6214.502839772807, 6307.5584709070845, 6336.398042185985, 6349.333320001426, 6359.682015899187, 4933.0, 4943.950560494421, 4944.0, 4945.455999840015, 4976.278439626234, 4255.0, 4354.978225108917, 4359.0, 4381.846635367747, 4398.56014398564, 6003.219581454383, 6043.0, 6068.939310606926, 6077.848053798691, 6095.512359011268, 6388.680565971683, 6430.517711171681, 6435.324290949477, 6440.935590644127, 6441.71012304438, 5947.940520594739, 6008.759649614054, 6012.7895523657335, 6016.819495415145, 6018.518518518508, 5529.778808847601, 5559.972200139055, 5574.749136288877, 5583.497485226349, 5598.244237027949, 6548.672566371758, 6625.17185351831, 6684.465242780566, 6691.631960242136, 6697.162854643072, 6491.837704057436, 6509.056186852975, 6513.674316284072, 6513.902291465726, 6527.771527996498, 6730.0, 6787.4230690389895, 6787.592744435349, 6792.388685018372, 6799.422049125879, 6217.378262173746, 6395.392437718409, 6396.58422202574, 6404.871902562016, 6413.198350206224, 6320.399562041599, 6353.396427339396, 6400.199975003125, 6483.870322593616, 6496.707648155939, 6123.496151642784, 6199.194104766351, 6199.938000619936, 6202.441780239619, 6234.937650623617, 6273.121762953099, 6273.121762953099, 6278.843028924313, 6281.96859015702, 6298.653574053471, 6255.655938923403, 6279.089532017741, 6280.5289603279025, 6283.5601507895835, 6288.622682639057, 6117.035835319669, 6153.2000839890525, 6214.471769899609, 6246.781362652286, 6311.431971122621, 6123.679189354865, 6151.661658608819, 6159.815205543661, 6164.0, 6164.106204600397], "value": 5866.960051949348, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [652.5758257132866, 714.1144980224522, 738.4092725819346, 1143.571160814694, 1171.970700732482, 28894.0, 36526.63473365265, 37515.0, 37531.4370284446, 37532.81233593835, 38600.22799544007, 38972.0, 39022.0, 39064.21871562567, 39087.0, 5620.353659329168, 5726.856828579298, 7137.036500072476, 7270.672819723099, 7274.818129546751, 6998.405135563483, 7145.821354466128, 7147.785566433015, 7148.642567871611, 7222.4583156263225, 7241.855162896739, 7322.524035937647, 7323.414126869838, 7324.63376831159, 7326.047613810224, 7231.0, 7290.198078211366, 7294.051773269441, 7298.9635051824935, 7304.196538380774, 7013.123359580052, 7058.647067646649, 7059.0, 7059.435245180373, 7066.540674856169, 34897.127571810655, 34997.47503787445, 35045.47431788525, 35056.0, 35071.824640876635, 3513.6486351364915, 3545.3972824619877, 3546.343926373603, 3550.715942724576, 3551.200979779555, 27764.722352776414, 27825.16524504268, 27876.303092422648, 27876.860615696995, 27901.0, 3790.298794722985, 3810.9809450952566, 3812.046988252937, 3823.025128592219, 3826.7512611680154, 192309.0, 193504.0, 194014.0, 194330.99999999857, 194375.0, 48139.0, 48182.0, 48235.5176448231, 48291.0, 48383.0, 49508.75245623749, 49906.0, 49958.50041499538, 49991.25013124733, 50018.0, 15176.544703658908, 15261.923690381476, 15274.31265593051, 15278.770818437732, 15283.847161528465, 10915.290506117077, 11107.111431085494, 12449.128561000643, 12650.746985060432, 12665.556705515262, 12006.339651319262, 12112.69718257033, 12135.87864121365, 12155.878441215475, 12286.201396909173, 4734.313524538922, 4758.762061896891, 4775.21209000511, 4777.498362671939, 4788.712677239377, 1063.31416236527, 1080.9405482698528, 1085.8859819718973, 1111.994440027811, 1137.1869113583828, 25450.618240726402, 25773.0, 25975.350616234744, 26063.609045864327, 26147.869260653573, 1181.1259667845732, 1183.8930599888959, 1209.455744914792, 1210.1589395370133, 1215.4348228073989, 12916.0, 13527.526536571173, 13562.050656453954, 13608.863911360957, 13620.727585448236, 227179.0, 227892.0, 228365.0, 228410.0, 228927.0, 50401.0, 50406.747966259936, 50444.74777626088, 50481.24278135831, 50541.24188137181, 1469.9118052916858, 1520.2474774986451, 1534.823495298055, 1536.4084827341553, 1539.6150962259435, 23819.880900595384, 24870.626940595543, 25282.024881167657, 28698.0, 30491.0, 48195.5180448191, 48630.513694864014, 48639.513604863496, 48644.0, 48903.75548122237, 191382.0, 191431.0, 192314.0, 192842.0, 193164.0, 30231.58147348163, 31282.843585781924, 31571.684283156876, 31725.0, 31725.36549269048, 40106.0, 41747.582524174366, 41789.58210417857, 41801.372979404725, 41838.581614183466, 29895.850520747255, 30526.694733052384, 30571.847140764155, 30999.690003100583, 32260.516092258167, 31017.8449107753, 31099.533506997865, 31514.0, 31522.68477315289, 32383.51424728584, 35936.28127437489, 35984.0, 36184.09539761527, 36280.4557931626, 36301.45547816732, 28652.713472865005, 29917.551236731902, 30576.0, 30697.846510767304, 31575.21061973469, 24520.509589808462, 24840.0, 25013.74986250187, 25091.49817003613, 25185.874070630263], "value": 16665.414845135576, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [375.1211836291269, 428.4686988134712, 448.51784331843254, 456.1743244727044, 463.7124982510843, 19798.60402791943, 20629.484262893435, 20970.37088887332, 21269.468263293424, 21412.25057122997, 22724.65913011306, 22843.200487982926, 22917.885410572922, 22943.541129177407, 23582.882085589546, 5458.863528411782, 5516.255305533762, 5517.586181036419, 5572.247746554204, 5650.5479561634975, 3439.4152993990956, 3585.103724068983, 5521.641093328921, 5596.188552659883, 5596.636218645795, 3560.3413368526903, 3572.374834403982, 5372.597055220855, 5455.781768729246, 5531.2532808071, 5386.218998245272, 5483.396826349099, 5514.035043867328, 5521.199426083235, 5523.447655234485, 5023.397192336943, 5026.623003274769, 5026.723530205837, 5028.220625803006, 5033.446320904697, 26129.73870261311, 26211.344716382053, 26436.603450948252, 26470.07354742594, 26951.595726064126, 2113.7357830271217, 2171.6416791229426, 2173.1741938063433, 2173.413178441812, 2173.467500462381, 16312.429064982787, 17042.0, 17058.829411705847, 17215.245560710227, 17531.0, 2136.380449669594, 2163.729533808274, 2201.0315461197183, 2203.030666506748, 2219.178903805602, 108658.91132572434, 115539.4223028888, 117216.0, 117990.0, 118423.0, 11184.328940263611, 11370.260933039326, 12532.87467125317, 12544.560940367044, 12592.181508201938, 17358.65282694339, 17441.0, 17561.824381756018, 17568.56078598045, 17719.911400443172, 9129.589168487433, 9195.862062069074, 9277.072292770668, 9289.396189247676, 9456.196223321094, 8587.871181932276, 8602.913970860336, 8831.55842207887, 8934.73195804127, 8935.731928042169, 7842.5686587237105, 7896.368290536743, 7973.0, 8086.838263234702, 8134.75595732129, 2140.6987488449977, 2178.6470611739283, 2205.283282933053, 2234.4078819112947, 2299.195281651442, 1020.6121673763922, 1045.7803861189084, 1050.695298363466, 1051.7686109055996, 1054.6835949215206, 18916.810831891504, 20030.298939537337, 20164.69752953707, 20221.191152354037, 20532.486687832923, 1057.7522764126231, 1065.5471424644495, 1065.6217042949797, 1074.9892501075046, 1078.8058149533003, 10948.343099413902, 10953.123750100132, 11052.281601696024, 11105.555777768799, 11165.385903775208, 470377.0, 473709.0, 477398.0, 482145.0, 484120.0, 96564.0, 96573.0, 97167.51416242874, 99323.50338248406, 103194.0, 1155.1451925575182, 1194.116353898109, 1216.4890745886748, 1221.865594784573, 1230.366361323919, 16836.0, 16912.74630880513, 17166.141692915055, 17326.0, 17828.3760068397, 42585.574144258164, 43376.34935475907, 43657.781711091244, 44092.11815763731, 44424.77787611041, 53141.468585313654, 55757.0, 55860.16209756776, 56780.71609641925, 56932.715336423054, 9213.76965575866, 9273.582688778886, 9337.953310233406, 9356.625734970525, 9474.857877131986, 14272.785908211592, 14435.422583096979, 14470.782938255725, 14477.0, 14972.925135374253, 11611.767764644828, 13013.869861301266, 13047.608571742863, 13132.540361087316, 13291.136076155019, 12341.938290308492, 12447.751044978868, 12546.49814007466, 12619.179753316004, 12808.935955320163, 13388.263645499592, 13446.596602101952, 13524.459021639024, 13925.79111313311, 13945.930270348583, 9304.395214311047, 9367.812643746951, 9431.151196392357, 9765.951170244387, 9829.803403931739, 12536.185147965352, 12737.490500380252, 12755.362231888554, 12898.419571119135, 13267.203967761965], "value": 10034.320554205102, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1327.1373607155347, 1383.1286289637526, 1386.6394737368284, 1387.4519564771915, 1408.2958520739637, 10842.132629389649, 11220.887791122086, 11237.269577477458, 11253.718657033569, 11266.155038372113, 11043.83434248485, 11082.390468524229, 11099.889001109987, 11181.888181118187, 11193.16051296152, 2348.80035197008, 2383.8688872112025, 2385.0936644075314, 2390.6175011998, 2391.2228525729206, 2036.0735865181382, 2315.073970411832, 2376.0495801679294, 2384.5350156719446, 2392.091005417939, 2049.497873021112, 2238.7649296823843, 2363.279199844047, 2364.5625559271502, 2370.395549134968, 2312.838101332907, 2334.2297041976108, 2342.074880422232, 2354.0466111224896, 2354.505553833697, 2318.5594736999915, 2342.3909783456425, 2344.2264052862515, 2345.812335013195, 2361.645753137026, 11270.0, 11347.319160850375, 11356.148288878367, 11360.204785665006, 11360.318380897173, 6387.840303992391, 6576.441002514792, 6583.835404114935, 6603.537752357337, 6608.074869518271, 3665.871694490707, 3840.577536470986, 4047.97976010121, 4057.8173982170874, 4102.425660407545, 1343.9865601344056, 1372.2726954714017, 1413.6465883529117, 1420.0911416693325, 1420.6235347632885, 17448.738268925976, 17546.824531754773, 17605.383811566535, 17644.823551764446, 17651.646967060715, 5203.9219411708855, 5209.869753256199, 5343.679379237258, 5473.7263136843, 5527.336719593594, 12272.938635306766, 12311.19977201497, 12347.19743216688, 12348.506059757688, 12348.567800126952, 6394.616323020634, 6428.903566446507, 6429.903551446732, 6435.22777266731, 6441.259255185713, 5946.316173640001, 6014.759409623566, 6103.542234332443, 6118.60229085108, 6165.445109940127, 4220.810063547147, 4222.239996800606, 4266.5946735060115, 4272.957270427318, 4346.695731298842, 1084.7017070305728, 1229.661842993184, 1231.0643910627987, 1260.7541529401678, 1267.3029833591488, 758.1887380502919, 878.1613559051091, 904.653141654344, 920.663957655451, 928.4986107501874, 6493.51298652603, 6514.413702766774, 6568.967155164193, 6572.671366431659, 6673.332666733288, 967.3712087143413, 970.5098925042809, 971.5288085278635, 979.456401697065, 989.1098011789303, 4052.9392059118545, 4146.212219678312, 4165.271077561415, 4175.144095460423, 4198.538160802309, 15209.923950380176, 15366.846331536764, 15507.689846203013, 15534.84465155334, 15628.140452275235, 14841.257937103102, 15420.614484637974, 15424.074555526704, 15434.459793907385, 15451.768223476656, 9201.861972070425, 9309.208717258973, 9346.439213647067, 9376.578053987587, 9418.811623767486, 6623.569467984566, 6961.547499412522, 7135.8572828544175, 7217.7112915484895, 7272.490925635367, 13349.466021359036, 13500.392482338515, 13509.72980540364, 13768.311584420939, 13783.0, 13814.171149731048, 13850.30748462593, 13874.861251387762, 13984.930075349557, 14300.642483937569, 9271.86092208604, 9498.382605130644, 9513.0, 9518.904810952079, 9642.951785241028, 11121.16591255643, 11166.776664466503, 11190.496427660937, 11198.160137989846, 11231.269967452416, 9232.676856309998, 9384.859227111463, 9388.295877809054, 9399.060093990547, 9534.570944307661, 9110.590023449093, 9173.541322933694, 9262.073792620684, 9362.157405833508, 9553.0, 7565.886511702219, 7749.535027898119, 7784.260495252942, 7786.454948153575, 7787.571683557232, 6263.812085637438, 6276.717547710456, 6318.684065796784, 6321.557490975588, 6511.511636627177, 5993.880122397615, 6147.815565533041, 6149.477294430024, 6227.470664993526, 6293.276273228455], "value": 5389.035117342249, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [418.20749679357607, 419.9496060472743, 422.35590724145675, 422.57531181162926, 425.5552947170207, 5746.798862039829, 5771.595988280822, 5807.41925807419, 5837.270341207349, 5837.445442682938, 5664.858378540539, 5715.256846661767, 5745.563609097726, 5749.195112684228, 5783.479486846183, 4266.424032755569, 4356.106998065389, 4380.2115619188535, 4392.582704643054, 4554.407926969498, 4367.803448844794, 4381.75900325482, 4382.255016647162, 4386.850443282784, 4397.50484835156, 4317.266064768997, 4353.41228934093, 4357.455318085239, 4358.411614432059, 4368.082702632451, 4294.677899157576, 4297.247981603223, 4311.676624253194, 4337.306031035035, 4352.847650332224, 3810.1998580298173, 3811.714121440903, 3811.8475260989526, 3814.2371525694693, 3822.73240873139, 5608.46719561641, 5611.523020543218, 5621.915671264935, 5641.323041235078, 5647.491725744661, 4905.754712264408, 5036.11867923112, 5109.897802043938, 5126.948730512684, 5166.715830629314, 6047.879042419127, 6063.575549711522, 6072.817815465543, 6074.787382441594, 6110.450059494622, 4284.678649101299, 4304.720193187432, 4306.224879521685, 4307.375430562551, 4316.115196384733, 6029.0, 6180.907286390708, 6247.00191877199, 6388.137601423841, 6444.066779966039, 4249.192653395907, 4322.579355560896, 4366.890827729332, 4374.256376416017, 4391.560843915583, 4416.3596278539435, 4421.491528474202, 4439.911201775946, 4441.666874984389, 4453.220686379936, 4918.426103646871, 5065.39215294167, 5096.286519887219, 5100.081985242692, 5105.948940510547, 6103.908441373383, 6104.877902441926, 6112.938870611237, 6114.785982490592, 6138.943453942979, 1516.8255650600101, 1517.855803698647, 1518.4305885293015, 1520.5818399939883, 1522.14759734549, 1011.812814629296, 1028.6965345223177, 1038.924712922122, 1046.5238316565944, 1047.9004494573003, 5869.155703014532, 5873.4126587340925, 5883.970580147072, 5889.38161493037, 5935.73289201987, 6190.0714892765545, 6254.812355629248, 6258.874822503524, 6263.71813268404, 6263.71813268404, 5685.459881311269, 5759.107338362561, 5793.275840519935, 5802.535797136304, 5916.313850552572, 3304.545999760098, 3312.1222875937897, 3312.635610082889, 3313.618933822593, 3317.457382317234, 6463.192100987377, 6524.151860258136, 6529.347065293433, 6596.571222870594, 6599.571027883172, 6418.614883107029, 6436.227652681709, 6449.3873082057125, 6452.806415807533, 6456.386643268882, 6256.279523131085, 6419.2296924369375, 6481.902771458432, 6483.286838447767, 6494.123293355526, 6232.657203853651, 6243.875122497434, 6264.655443950626, 6271.867565027913, 6282.560220784684, 6151.200343955257, 6172.197614310111, 6213.2233470816145, 6223.937760622517, 6250.531210159347, 6516.315786842313, 6542.803715888341, 6546.705398257185, 6553.246376666744, 6561.967190164019, 4372.409724687126, 4388.9122217556105, 4393.0, 4398.934015989698, 4407.206702793528, 6073.696315184311, 6221.5333849960525, 6274.874502510015, 6275.246970363585, 6305.337939516285, 6068.2692788244585, 6068.666223357758, 6079.817605471843, 6081.939180608314, 6087.8782424350375, 5875.265591801025, 6098.451139397298, 6110.205673262448, 6111.358307377839, 6114.816555503342, 6112.2970858351855, 6121.540884433596, 6133.0, 6137.3248942617165, 6150.815475535741, 5961.701914904151, 6149.493374123368, 6150.7847225346895, 6163.660998645118, 6177.887980163614, 5958.0, 5981.790637327673, 5999.4900433463645, 6022.307434644897, 6026.427489388501], "value": 4632.592802669676, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [860.5955201055501, 878.8769572259885, 885.7254251182138, 885.8538341173706, 901.387056801375, 14669.486567970123, 15377.0, 15383.461578844743, 15384.384624615002, 15392.076475411483, 16465.753013704772, 17175.398861039866, 17237.22432490538, 17275.568110797238, 17287.74068388975, 993.5926270229207, 1019.4240254256322, 1019.8623185869886, 1022.3405903192437, 1023.0179028133001, 986.659602437158, 1020.7856350166473, 1029.4698230411343, 1030.345730461155, 1033.8604288421043, 1082.3505896462138, 1085.7122862441458, 1092.2954694222221, 1092.464692300775, 1093.8031154392206, 1078.0728573426848, 1081.2431298091367, 1082.0586090101583, 1083.7669900971289, 1086.8858769829174, 794.8052727081844, 796.8087658961865, 798.0981490915286, 798.2376830127273, 799.1329407592768, 15587.454439094578, 15601.687966240612, 16154.596135096599, 18003.639927201515, 18231.72652410215, 19087.713684294748, 19088.33190838314, 19381.418557443158, 19395.709064364044, 19580.608387832304, 14577.0, 14666.046706964013, 14762.114273143647, 14802.555923322318, 14850.702985940221, 3418.162550175223, 3438.381091403546, 3454.827258637083, 3464.2898205867737, 3464.3071385722706, 87149.56425217833, 88871.55564222203, 89503.0, 90109.0, 91615.0, 14300.856991429953, 14598.854011459962, 14730.705385892223, 14731.779023314659, 14799.186044767426, 25387.0, 25850.095246666275, 27108.32229194286, 27196.59205111925, 27321.590176147372, 8357.498550087013, 8432.831343373098, 8516.914830851736, 8526.31789456842, 8564.785880352916, 3860.0, 3941.921161576752, 3946.1910308387287, 3949.940750888739, 3961.1087505311652, 4501.707389019703, 4516.683832131785, 4545.727256364628, 4554.726716397026, 4586.220342541776, 910.1808372464834, 927.5067141901533, 934.5700977550267, 939.2439086535404, 941.5057095025167, 676.8037269191977, 685.4961603221628, 686.4165459359605, 689.2556039477353, 754.3927138653437, 11720.472578733978, 12142.45358958849, 12468.251904885736, 12499.687507812196, 12520.81218781719, 677.3023785500942, 711.2034521336136, 740.9184989651134, 741.6032422653855, 765.6171914042978, 5377.655586103474, 5748.827535173951, 5823.621464604787, 5845.064789633639, 5876.970615146897, 80326.0, 80556.7359753624, 81557.0, 83995.5800220995, 84561.0, 73655.63172184105, 74315.0, 75272.0, 76351.0, 77832.61083694658, 64515.67742161259, 65314.0, 66164.66917665477, 66692.33307666861, 66968.0, 11191.888081119085, 11591.594294199662, 11635.069194464253, 11651.53393864236, 11791.88208117908, 33304.666953330154, 33521.32957340791, 34096.31807363888, 34221.48667769936, 35232.471512926815, 41128.0, 41424.1715165701, 42101.78949105235, 42821.0, 43519.56480435282, 15022.248887555797, 15209.771853421986, 15332.770008450105, 15474.613134671723, 15602.921985390001, 16646.25091870893, 16941.0, 16999.91500042492, 17003.40488082911, 17004.319827206775, 15967.8403215971, 15980.920095399448, 16140.435084772447, 16630.584235394213, 16658.916705416395, 15557.76663350028, 15607.375704972128, 15640.2961866714, 15851.603709907344, 16100.436484722979, 15564.066156030674, 15741.44904928322, 15829.050256984618, 15895.046297222203, 15950.441734539236, 10405.167586592903, 10597.47012649349, 10836.078933290759, 10957.287776294515, 11101.833472498081, 11638.068954483451, 11909.880901190878, 13719.176849389069, 13900.791488127485, 14211.107612807986], "value": 8156.927308720627, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1479.3000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 111.0, 109.0, 108.0, 110.0, 108.0, 108.0, 108.0, 109.0, 109.0], "value": 108.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 115.0, 121.0, 153.0, 118.0, 117.0, 121.0, 122.0, 139.0, 117.0], "value": 126.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 160.0, 160.0, 161.0, 161.0, 160.0, 160.0, 161.0, 161.0], "value": 160.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 73.0, 111.0, 74.0, 74.0, 74.0, 74.0, 102.0, 73.0], "value": 80.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 105.0, 83.0, 82.0, 83.0, 95.0, 82.0, 83.0, 108.0], "value": 88.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 114.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0], "value": 113.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 149.0, 147.0, 146.0, 146.0, 146.0, 146.0, 147.0, 146.0, 147.0], "value": 146.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0], "value": 94.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [44.0, 46.0, 47.0, 45.0, 45.0, 45.0, 46.0, 46.0, 45.0, 45.0], "value": 45.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 55.0, 56.0, 56.0, 57.0, 55.0, 56.0, 56.0, 56.0, 55.0], "value": 56.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 80.0, 82.0, 80.0, 80.0, 80.0, 80.0, 80.0, 80.0], "value": 80.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 134.0, 124.0, 127.0, 125.0, 136.0, 130.0, 124.0, 168.0, 123.0], "value": 132.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [193.0, 173.0, 180.0, 200.0, 180.0, 181.0, 176.0, 183.0, 179.0, 177.0], "value": 182.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 65.0, 65.0, 65.0, 64.0, 66.0, 65.0, 63.0, 63.0, 65.0], "value": 64.9, "unit": "score"}]}]} 07:40:14 INFO - Return code: 0 07:40:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:40:15 INFO - # TBPL SUCCESS # 07:40:15 INFO - Running post-action listener: _resource_record_post_action 07:40:15 INFO - [mozharness: 2017-05-05 14:40:15.042096Z] Finished run-tests step (success) 07:40:15 INFO - Running post-run listener: _resource_record_post_run 07:40:15 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 2744320; Write bytes: 359329792; Read time: 536; Write time: 236664 07:40:15 INFO - TinderboxPrint: CPU usage
12.9% 07:40:15 INFO - TinderboxPrint: I/O read bytes / time
2,744,320 / 536 07:40:15 INFO - TinderboxPrint: I/O write bytes / time
359,329,792 / 236,664 07:40:15 INFO - TinderboxPrint: CPU idle
8,285.7 (87.1%) 07:40:15 INFO - TinderboxPrint: CPU user
1,185.8 (12.5%) 07:40:15 INFO - TinderboxPrint: Swap in / out
0 / 0 07:40:15 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 103415808; Read time: 0; Write time: 84340 07:40:15 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 2740224; Write bytes: 255320064; Read time: 528; Write time: 152300 07:40:15 INFO - Running post-run listener: _upload_blobber_files 07:40:15 INFO - Blob upload gear active. 07:40:15 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:40:15 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 07:40:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:40:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:40:16 INFO - (blobuploader) - INFO - Open directory for files ... 07:40:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:40:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:40:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:40:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:40:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:40:16 INFO - (blobuploader) - INFO - Done attempting. 07:40:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:40:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:40:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:40:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:40:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:40:17 INFO - (blobuploader) - INFO - Done attempting. 07:40:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:40:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:40:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:40:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:40:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:40:20 INFO - (blobuploader) - INFO - Done attempting. 07:40:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 07:40:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:40:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:40:21 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 07:40:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:40:21 INFO - (blobuploader) - INFO - Done attempting. 07:40:21 INFO - (blobuploader) - INFO - Iteration through files over. 07:40:21 INFO - Return code: 0 07:40:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:40:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:40:21 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3ae5defca99b6fb9379590d1306c5b29415c0d85d39e007883ebc7902a938056bed5452d1878d3bd666f44a45b3062199341a82b00c6d0fe6921820cf046add9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f02db77a6e15cc7c356234a688a97031b3806d86bb3270dd040d18dd3d24a583d8edcca15efbd1124d6156d61e6843ff0eb29410688a09c0c0f17a95e4dd71f2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/604bf8ce7383ca5bc7f7add25af9fec5c67355c34dee8bebb75b6ebc0c0b9ce8fcd4466a60108407705629f2ab5d7a5b077831abd0e09ecd0788c40d02547569", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8b657fd82f36bf9a3b4eeee24890733de09d32a84c2a230f1d9e09ade7ea47a5055a759826e3a5f288e1331bab0f42491e011789c069f4f2ec96cdee78bdbad2"} 07:40:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:40:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:40:21 INFO - Contents: 07:40:21 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3ae5defca99b6fb9379590d1306c5b29415c0d85d39e007883ebc7902a938056bed5452d1878d3bd666f44a45b3062199341a82b00c6d0fe6921820cf046add9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f02db77a6e15cc7c356234a688a97031b3806d86bb3270dd040d18dd3d24a583d8edcca15efbd1124d6156d61e6843ff0eb29410688a09c0c0f17a95e4dd71f2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/604bf8ce7383ca5bc7f7add25af9fec5c67355c34dee8bebb75b6ebc0c0b9ce8fcd4466a60108407705629f2ab5d7a5b077831abd0e09ecd0788c40d02547569", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8b657fd82f36bf9a3b4eeee24890733de09d32a84c2a230f1d9e09ade7ea47a5055a759826e3a5f288e1331bab0f42491e011789c069f4f2ec96cdee78bdbad2"} 07:40:21 INFO - Running post-run listener: copy_logs_to_upload_dir 07:40:21 INFO - Copying logs to upload dir... 07:40:21 INFO - mkdir: /builds/slave/test/build/upload/logs 07:40:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1246.072003 ========= master_lag: 0.26 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2017-05-05 07:40:21.464475) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-05 07:40:21.468918) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1493993891.533124-168417341 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3ae5defca99b6fb9379590d1306c5b29415c0d85d39e007883ebc7902a938056bed5452d1878d3bd666f44a45b3062199341a82b00c6d0fe6921820cf046add9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f02db77a6e15cc7c356234a688a97031b3806d86bb3270dd040d18dd3d24a583d8edcca15efbd1124d6156d61e6843ff0eb29410688a09c0c0f17a95e4dd71f2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/604bf8ce7383ca5bc7f7add25af9fec5c67355c34dee8bebb75b6ebc0c0b9ce8fcd4466a60108407705629f2ab5d7a5b077831abd0e09ecd0788c40d02547569", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8b657fd82f36bf9a3b4eeee24890733de09d32a84c2a230f1d9e09ade7ea47a5055a759826e3a5f288e1331bab0f42491e011789c069f4f2ec96cdee78bdbad2"} build_url:https://queue.taskcluster.net/v1/task/YwrTf5ZhTJmSd6M0Ez5Zog/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011216 build_url: 'https://queue.taskcluster.net/v1/task/YwrTf5ZhTJmSd6M0Ez5Zog/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3ae5defca99b6fb9379590d1306c5b29415c0d85d39e007883ebc7902a938056bed5452d1878d3bd666f44a45b3062199341a82b00c6d0fe6921820cf046add9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f02db77a6e15cc7c356234a688a97031b3806d86bb3270dd040d18dd3d24a583d8edcca15efbd1124d6156d61e6843ff0eb29410688a09c0c0f17a95e4dd71f2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/604bf8ce7383ca5bc7f7add25af9fec5c67355c34dee8bebb75b6ebc0c0b9ce8fcd4466a60108407705629f2ab5d7a5b077831abd0e09ecd0788c40d02547569", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8b657fd82f36bf9a3b4eeee24890733de09d32a84c2a230f1d9e09ade7ea47a5055a759826e3a5f288e1331bab0f42491e011789c069f4f2ec96cdee78bdbad2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-05 07:40:21.500263) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 07:40:21.500604) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1493993891.533124-168417341 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004465 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 07:40:21.554188) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-05 07:40:21.554510) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-05 07:40:21.554796) ========= ========= Total master_lag: 0.39 =========