builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-072 starttime: 1489852285.45 results: success (0) revision: e1576dd8bd9d3a4ca418cf347133b8a4957ddeca ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-18 08:51:25.453639) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-18 08:51:25.454020) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-18 08:51:25.454301) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-18 08:51:25.479257) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-18 08:51:25.479532) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=199fb4c72b90437b990b419b000001c5-1489838326.884481-504369783 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-03-18 08:51:26-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 37.7M=0s 2017-03-18 08:51:26 (37.7 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.254410 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-18 08:51:25.747628) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-18 08:51:25.747947) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=199fb4c72b90437b990b419b000001c5-1489838326.884481-504369783 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013592 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-18 08:51:25.797304) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-18 08:51:25.797764) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev e1576dd8bd9d3a4ca418cf347133b8a4957ddeca --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 e1576dd8bd9d3a4ca418cf347133b8a4957ddeca --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=199fb4c72b90437b990b419b000001c5-1489838326.884481-504369783 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-03-18 08:51:26,417 truncating revision to first 12 chars 2017-03-18 08:51:26,417 Setting DEBUG logging. 2017-03-18 08:51:26,417 attempt 1/10 2017-03-18 08:51:26,417 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/e1576dd8bd9d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-18 08:51:27,669 unpacking tar archive at: mozilla-central-e1576dd8bd9d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.594228 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-18 08:51:27.405808) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-18 08:51:27.406279) ========= script_repo_revision: e1576dd8bd9d3a4ca418cf347133b8a4957ddeca ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-18 08:51:27.406919) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-18 08:51:27.407411) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-18 08:51:27.417614) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-03-18 08:51:27.417978) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=199fb4c72b90437b990b419b000001c5-1489838326.884481-504369783 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:51:28 INFO - MultiFileLogger online at 20170318 08:51:28 in /builds/slave/test 08:51:28 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 08:51:28 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:51:28 INFO - {'append_to_log': False, 08:51:28 INFO - 'base_work_dir': '/builds/slave/test', 08:51:28 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 08:51:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:51:28 INFO - 'branch': 'Firefox-Non-PGO', 08:51:28 INFO - 'buildbot_json_path': 'buildprops.json', 08:51:28 INFO - 'config_files': ('talos/linux_config.py',), 08:51:28 INFO - 'default_actions': ('clobber', 08:51:28 INFO - 'read-buildbot-config', 08:51:28 INFO - 'download-and-extract', 08:51:28 INFO - 'populate-webroot', 08:51:28 INFO - 'create-virtualenv', 08:51:28 INFO - 'install', 08:51:28 INFO - 'run-tests'), 08:51:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:51:28 INFO - 'download_minidump_stackwalk': True, 08:51:28 INFO - 'download_symbols': 'ondemand', 08:51:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:51:28 INFO - 'tooltool.py': '/tools/tooltool.py', 08:51:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:51:28 INFO - '/tools/misc-python/virtualenv.py')}, 08:51:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:51:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:51:28 INFO - 'gecko_profile': False, 08:51:28 INFO - 'gecko_profile_interval': 0, 08:51:28 INFO - 'installer_path': 'installer.exe', 08:51:28 INFO - 'log_level': 'info', 08:51:28 INFO - 'log_name': 'talos', 08:51:28 INFO - 'log_to_console': True, 08:51:28 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:51:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:51:28 INFO - 'opt_config_files': (), 08:51:28 INFO - 'pip_index': False, 08:51:28 INFO - 'suite': 'dromaeojs-e10s', 08:51:28 INFO - 'system_bits': '32', 08:51:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:51:28 INFO - 'title': 'talos-linux64-ix-072', 08:51:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:51:28 INFO - 'use_talos_json': True, 08:51:28 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:51:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:51:28 INFO - 'work_dir': 'build'} 08:51:28 INFO - [mozharness: 2017-03-18 15:51:28.140260Z] Running clobber step. 08:51:28 INFO - Running pre-action listener: _resource_record_pre_action 08:51:28 INFO - Running main action method: clobber 08:51:28 INFO - rmtree: /builds/slave/test/build 08:51:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:51:29 INFO - Running post-action listener: _resource_record_post_action 08:51:29 INFO - [mozharness: 2017-03-18 15:51:29.061963Z] Finished clobber step (success) 08:51:29 INFO - [mozharness: 2017-03-18 15:51:29.062063Z] Running read-buildbot-config step. 08:51:29 INFO - Running pre-action listener: _resource_record_pre_action 08:51:29 INFO - Running main action method: read_buildbot_config 08:51:29 INFO - Using buildbot properties: 08:51:29 INFO - { 08:51:29 INFO - "project": "", 08:51:29 INFO - "product": "firefox", 08:51:29 INFO - "who": "archaeopteryx@coole-files.de", 08:51:29 INFO - "installer_path": "public/build/target.tar.bz2", 08:51:29 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 08:51:29 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 08:51:29 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 08:51:29 INFO - "stage_platform": "linux64", 08:51:29 INFO - "basedir": "/builds/slave/test", 08:51:29 INFO - "buildnumber": 725, 08:51:29 INFO - "platform": "ubuntu64_hw", 08:51:29 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 08:51:29 INFO - "slavebuilddir": "test", 08:51:29 INFO - "taskId": "FnMZFUrUScWBJoMt4zanDQ", 08:51:29 INFO - "branch": "mozilla-central", 08:51:29 INFO - "script_repo_revision": "production", 08:51:29 INFO - "revision": "e1576dd8bd9d3a4ca418cf347133b8a4957ddeca", 08:51:29 INFO - "slavename": "talos-linux64-ix-072", 08:51:29 INFO - "repo_path": "mozilla-central" 08:51:29 INFO - } 08:51:29 INFO - Finding installer, test and symbols from parent task. 08:51:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FnMZFUrUScWBJoMt4zanDQ'}, attempt #1 08:51:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FnMZFUrUScWBJoMt4zanDQ'}, attempt #1 08:51:30 INFO - Task dependencies: TEKF6SNBREeQZgQt-7oloQ 08:51:30 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TEKF6SNBREeQZgQt-7oloQ'}, attempt #1 08:51:30 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/TEKF6SNBREeQZgQt-7oloQ/artifacts/public/build/target.tar.bz2 08:51:30 INFO - Set test_url: https://queue.taskcluster.net/v1/task/TEKF6SNBREeQZgQt-7oloQ/artifacts/public/build/test_packages.json 08:51:30 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/TEKF6SNBREeQZgQt-7oloQ/artifacts/public/build/target.crashreporter-symbols.zip 08:51:30 INFO - Running post-action listener: _resource_record_post_action 08:51:30 INFO - [mozharness: 2017-03-18 15:51:30.562974Z] Finished read-buildbot-config step (success) 08:51:30 INFO - [mozharness: 2017-03-18 15:51:30.563188Z] Running download-and-extract step. 08:51:30 INFO - Running pre-action listener: _resource_record_pre_action 08:51:30 INFO - Running main action method: download_and_extract 08:51:30 INFO - mkdir: /builds/slave/test/build/tests 08:51:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:51:30 INFO - trying https://queue.taskcluster.net/v1/task/TEKF6SNBREeQZgQt-7oloQ/artifacts/public/build/target.test_packages.json 08:51:30 INFO - Downloading https://queue.taskcluster.net/v1/task/TEKF6SNBREeQZgQt-7oloQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 08:51:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TEKF6SNBREeQZgQt-7oloQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 08:51:31 INFO - Downloaded 986 bytes. 08:51:31 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 08:51:31 INFO - Using the following test package requirements: 08:51:31 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 08:51:31 INFO - u'common': [u'target.common.tests.zip'], 08:51:31 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 08:51:31 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 08:51:31 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 08:51:31 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 08:51:31 INFO - u'mozbase': [u'target.common.tests.zip'], 08:51:31 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 08:51:31 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 08:51:31 INFO - u'web-platform': [u'target.common.tests.zip', 08:51:31 INFO - u'target.web-platform.tests.zip'], 08:51:31 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 08:51:31 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 08:51:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TEKF6SNBREeQZgQt-7oloQ/artifacts/public/build/target.common.tests.zip 08:51:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TEKF6SNBREeQZgQt-7oloQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:51:31 INFO - Fetch https://queue.taskcluster.net/v1/task/TEKF6SNBREeQZgQt-7oloQ/artifacts/public/build/target.common.tests.zip into memory 08:51:32 INFO - Expected file size: 58186466 08:51:32 INFO - Obtained file size: 58186466 08:51:42 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 08:51:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TEKF6SNBREeQZgQt-7oloQ/artifacts/public/build/target.common.tests.zip 08:51:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TEKF6SNBREeQZgQt-7oloQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:51:42 INFO - Fetch https://queue.taskcluster.net/v1/task/TEKF6SNBREeQZgQt-7oloQ/artifacts/public/build/target.common.tests.zip into memory 08:51:43 INFO - Expected file size: 58186466 08:51:43 INFO - Obtained file size: 58186466 08:51:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TEKF6SNBREeQZgQt-7oloQ/artifacts/public/build/target.talos.tests.zip 08:51:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TEKF6SNBREeQZgQt-7oloQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 08:51:55 INFO - Fetch https://queue.taskcluster.net/v1/task/TEKF6SNBREeQZgQt-7oloQ/artifacts/public/build/target.talos.tests.zip into memory 08:51:55 INFO - Expected file size: 14404171 08:51:55 INFO - Obtained file size: 14404171 08:51:56 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')]} 08:51:56 INFO - trying https://queue.taskcluster.net/v1/task/TEKF6SNBREeQZgQt-7oloQ/artifacts/public/build/target.tar.bz2 08:51:56 INFO - Downloading https://queue.taskcluster.net/v1/task/TEKF6SNBREeQZgQt-7oloQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 08:51:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TEKF6SNBREeQZgQt-7oloQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 08:51:57 INFO - Downloaded 61837047 bytes. 08:51:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TEKF6SNBREeQZgQt-7oloQ/artifacts/public/build/target.tar.bz2 08:51:57 INFO - mkdir: /builds/slave/test/properties 08:51:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:51:57 INFO - Writing to file /builds/slave/test/properties/build_url 08:51:57 INFO - Contents: 08:51:57 INFO - build_url:https://queue.taskcluster.net/v1/task/TEKF6SNBREeQZgQt-7oloQ/artifacts/public/build/target.tar.bz2 08:51:57 INFO - Running post-action listener: _resource_record_post_action 08:51:57 INFO - Running post-action listener: set_extra_try_arguments 08:51:57 INFO - [mozharness: 2017-03-18 15:51:57.366339Z] Finished download-and-extract step (success) 08:51:57 INFO - [mozharness: 2017-03-18 15:51:57.366435Z] Running populate-webroot step. 08:51:57 INFO - Running pre-action listener: _resource_record_pre_action 08:51:57 INFO - Running main action method: populate_webroot 08:51:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:51:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:51:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:51:57 INFO - '--tppagecycles', 08:51:57 INFO - '7'], 08:51:57 INFO - 'tests': ['tsvgm']}}, 08:51:57 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:51:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:51:57 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:51:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:51:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:51:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:51:57 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:51:57 INFO - 'talos_options': ['--disable-e10s'], 08:51:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:51:57 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:51:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:51:57 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:51:57 INFO - 'talos_options': ['--disable-e10s'], 08:51:57 INFO - 'tests': ['damp', 'tps']}, 08:51:57 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:51:57 INFO - 'tests': ['damp', 'tps']}, 08:51:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:51:57 INFO - 'tests': ['dromaeo_dom']}, 08:51:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:51:57 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:51:57 INFO - 'tests': ['basic_compositor_video']}, 08:51:57 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:51:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:51:57 INFO - 'tests': ['a11yr', 08:51:57 INFO - 'ts_paint', 08:51:57 INFO - 'tpaint', 08:51:57 INFO - 'sessionrestore', 08:51:57 INFO - 'sessionrestore_no_auto_restore', 08:51:57 INFO - 'tabpaint']}, 08:51:57 INFO - 'other-e10s': {'tests': ['a11yr', 08:51:57 INFO - 'ts_paint', 08:51:57 INFO - 'tpaint', 08:51:57 INFO - 'sessionrestore', 08:51:57 INFO - 'sessionrestore_no_auto_restore', 08:51:57 INFO - 'tabpaint']}, 08:51:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:51:57 INFO - 'ts_paint', 08:51:57 INFO - 'tpaint', 08:51:57 INFO - 'sessionrestore', 08:51:57 INFO - 'sessionrestore_no_auto_restore', 08:51:57 INFO - 'tabpaint']}, 08:51:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:51:57 INFO - 'ts_paint', 08:51:57 INFO - 'tpaint', 08:51:57 INFO - 'sessionrestore', 08:51:57 INFO - 'sessionrestore_no_auto_restore', 08:51:57 INFO - 'tabpaint']}, 08:51:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:51:57 INFO - 'tests': ['a11yr', 08:51:57 INFO - 'ts_paint', 08:51:57 INFO - 'tpaint', 08:51:57 INFO - 'sessionrestore', 08:51:57 INFO - 'sessionrestore_no_auto_restore', 08:51:57 INFO - 'tabpaint']}, 08:51:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:51:57 INFO - 'tests': ['a11yr', 08:51:57 INFO - 'ts_paint', 08:51:57 INFO - 'tpaint', 08:51:57 INFO - 'sessionrestore', 08:51:57 INFO - 'sessionrestore_no_auto_restore', 08:51:57 INFO - 'tabpaint']}, 08:51:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:51:57 INFO - 'tests': ['tsvgx', 08:51:57 INFO - 'tsvgr_opacity', 08:51:57 INFO - 'tart', 08:51:57 INFO - 'tscrollx', 08:51:57 INFO - 'cart', 08:51:57 INFO - 'tsvg_static']}, 08:51:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:51:57 INFO - 'tsvgr_opacity', 08:51:57 INFO - 'tart', 08:51:57 INFO - 'tscrollx', 08:51:57 INFO - 'cart', 08:51:57 INFO - 'tsvg_static']}, 08:51:57 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:51:57 INFO - 'talos_options': ['--disable-e10s'], 08:51:57 INFO - 'tests': ['tp5o']}, 08:51:57 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:51:57 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:51:57 INFO - 'talos_options': ['--disable-e10s', 08:51:57 INFO - '--xperf_path', 08:51:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:51:57 INFO - 'tests': ['tp5n']}, 08:51:57 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:51:57 INFO - 'talos_options': ['--xperf_path', 08:51:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:51:57 INFO - 'tests': ['tp5n']}}, 08:51:57 INFO - 'talos.zip': {'path': '', 08:51:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:51:57 INFO - Running post-action listener: _resource_record_post_action 08:51:57 INFO - [mozharness: 2017-03-18 15:51:57.379453Z] Finished populate-webroot step (success) 08:51:57 INFO - [mozharness: 2017-03-18 15:51:57.379542Z] Running create-virtualenv step. 08:51:57 INFO - Running pre-action listener: _resource_record_pre_action 08:51:57 INFO - Running main action method: create_virtualenv 08:51:57 INFO - Creating virtualenv /builds/slave/test/build/venv 08:51:57 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 08:51:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:51:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:51:57 INFO - Using real prefix '/usr' 08:51:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:51:57 INFO - Installing distribute.............................................................................................................................................................................................done. 08:51:59 INFO - Installing pip.................done. 08:51:59 INFO - Return code: 0 08:51:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:51:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:52:00 INFO - Reading from file tmpfile_stdout 08:52:00 INFO - Output received: 08:52:00 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) 08:52:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:52:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:52:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:52:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:52:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:52:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:00 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 0x224e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2346a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24ebb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e9290>, '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 0x24e9b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24ea040>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1489838326.884481-504369783', '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 08:52:00 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 08:52:00 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 08:52:00 INFO - Using env: {'DISPLAY': ':0', 08:52:00 INFO - 'HOME': '/home/cltbld', 08:52:00 INFO - 'LANG': 'en_US.UTF-8', 08:52:00 INFO - 'LANGUAGE': 'en_US:en', 08:52:00 INFO - 'LOGNAME': 'cltbld', 08:52:00 INFO - 'MAIL': '/var/mail/cltbld', 08:52:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:52:00 INFO - 'MOZ_NO_REMOTE': '1', 08:52:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:52:00 INFO - 'NO_EM_RESTART': '1', 08:52:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:52:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:52:00 INFO - 'PWD': '/builds/slave/test', 08:52:00 INFO - 'SHELL': '/bin/bash', 08:52:00 INFO - 'SHLVL': '1', 08:52:00 INFO - 'TERM': 'linux', 08:52:00 INFO - 'TMOUT': '86400', 08:52:00 INFO - 'USER': 'cltbld', 08:52:00 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1489838326.884481-504369783', 08:52:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:52:00 INFO - '_': '/tools/buildbot/bin/python'} 08:52:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:52:00 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 08:52:00 INFO - Cleaning up... 08:52:00 INFO - Return code: 0 08:52:00 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:52:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:52:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:52:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:52:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:52:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:00 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 0x224e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2346a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24ebb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e9290>, '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 0x24e9b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24ea040>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1489838326.884481-504369783', '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 08:52:00 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 08:52:00 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 08:52:00 INFO - Using env: {'DISPLAY': ':0', 08:52:00 INFO - 'HOME': '/home/cltbld', 08:52:00 INFO - 'LANG': 'en_US.UTF-8', 08:52:00 INFO - 'LANGUAGE': 'en_US:en', 08:52:00 INFO - 'LOGNAME': 'cltbld', 08:52:00 INFO - 'MAIL': '/var/mail/cltbld', 08:52:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:52:00 INFO - 'MOZ_NO_REMOTE': '1', 08:52:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:52:00 INFO - 'NO_EM_RESTART': '1', 08:52:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:52:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:52:00 INFO - 'PWD': '/builds/slave/test', 08:52:00 INFO - 'SHELL': '/bin/bash', 08:52:00 INFO - 'SHLVL': '1', 08:52:00 INFO - 'TERM': 'linux', 08:52:00 INFO - 'TMOUT': '86400', 08:52:00 INFO - 'USER': 'cltbld', 08:52:00 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1489838326.884481-504369783', 08:52:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:52:00 INFO - '_': '/tools/buildbot/bin/python'} 08:52:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:52:00 INFO - Downloading/unpacking psutil>=3.1.1 08:52:00 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 08:52:00 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 08:52:00 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 08:52:00 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 08:52:00 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 08:52:00 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 08:52:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:52:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:52:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:52:02 INFO - Installing collected packages: psutil 08:52:02 INFO - Running setup.py install for psutil 08:52:02 INFO - building 'psutil._psutil_linux' extension 08:52:02 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 08:52:02 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 08:52:02 INFO - building 'psutil._psutil_posix' extension 08:52:02 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 08:52:03 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 08:52:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:52:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:52:03 INFO - Successfully installed psutil 08:52:03 INFO - Cleaning up... 08:52:03 INFO - Return code: 0 08:52:03 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:52:03 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')]} 08:52:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:52:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:03 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')]} 08:52:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:52:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:03 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 0x224e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2346a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24ebb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e9290>, '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 0x24e9b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24ea040>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1489838326.884481-504369783', '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 08:52:03 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 08:52:03 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 08:52:03 INFO - Using env: {'DISPLAY': ':0', 08:52:03 INFO - 'HOME': '/home/cltbld', 08:52:03 INFO - 'LANG': 'en_US.UTF-8', 08:52:03 INFO - 'LANGUAGE': 'en_US:en', 08:52:03 INFO - 'LOGNAME': 'cltbld', 08:52:03 INFO - 'MAIL': '/var/mail/cltbld', 08:52:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:52:03 INFO - 'MOZ_NO_REMOTE': '1', 08:52:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:52:03 INFO - 'NO_EM_RESTART': '1', 08:52:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:52:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:52:03 INFO - 'PWD': '/builds/slave/test', 08:52:03 INFO - 'SHELL': '/bin/bash', 08:52:03 INFO - 'SHLVL': '1', 08:52:03 INFO - 'TERM': 'linux', 08:52:03 INFO - 'TMOUT': '86400', 08:52:03 INFO - 'USER': 'cltbld', 08:52:03 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1489838326.884481-504369783', 08:52:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:52:03 INFO - '_': '/tools/buildbot/bin/python'} 08:52:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:52:03 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:52:03 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 08:52:03 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 08:52:03 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 08:52:03 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 08:52:03 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 08:52:03 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 08:52:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:52:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:52:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:52:05 INFO - Installing collected packages: mozsystemmonitor 08:52:05 INFO - Running setup.py install for mozsystemmonitor 08:52:05 INFO - Successfully installed mozsystemmonitor 08:52:05 INFO - Cleaning up... 08:52:05 INFO - Return code: 0 08:52:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:52:05 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')]} 08:52:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:52:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:05 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')]} 08:52:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:52:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:05 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 0x224e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2346a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24ebb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e9290>, '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 0x24e9b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24ea040>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1489838326.884481-504369783', '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 08:52:05 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 08:52:05 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 08:52:05 INFO - Using env: {'DISPLAY': ':0', 08:52:05 INFO - 'HOME': '/home/cltbld', 08:52:05 INFO - 'LANG': 'en_US.UTF-8', 08:52:05 INFO - 'LANGUAGE': 'en_US:en', 08:52:05 INFO - 'LOGNAME': 'cltbld', 08:52:05 INFO - 'MAIL': '/var/mail/cltbld', 08:52:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:52:05 INFO - 'MOZ_NO_REMOTE': '1', 08:52:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:52:05 INFO - 'NO_EM_RESTART': '1', 08:52:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:52:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:52:05 INFO - 'PWD': '/builds/slave/test', 08:52:05 INFO - 'SHELL': '/bin/bash', 08:52:05 INFO - 'SHLVL': '1', 08:52:05 INFO - 'TERM': 'linux', 08:52:05 INFO - 'TMOUT': '86400', 08:52:05 INFO - 'USER': 'cltbld', 08:52:05 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1489838326.884481-504369783', 08:52:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:52:05 INFO - '_': '/tools/buildbot/bin/python'} 08:52:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:52:05 INFO - Downloading/unpacking jsonschema==2.5.1 08:52:05 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 08:52:05 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 08:52:05 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 08:52:05 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 08:52:05 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 08:52:05 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 08:52:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:52:07 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:52: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 08:52: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 08:52: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 08:52: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 08:52: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 08:52: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 08:52:08 INFO - Downloading functools32-3.2.3-2.tar.gz 08:52:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:52:08 INFO - warning: no files found matching '*.txt' 08:52:08 INFO - no previously-included directories found matching 'build' 08:52:08 INFO - no previously-included directories found matching 'dist' 08:52:08 INFO - no previously-included directories found matching '.git*' 08:52:08 INFO - Installing collected packages: jsonschema, functools32 08:52:08 INFO - Running setup.py install for functools32 08:52:08 INFO - warning: no files found matching '*.txt' 08:52:08 INFO - no previously-included directories found matching 'build' 08:52:08 INFO - no previously-included directories found matching 'dist' 08:52:08 INFO - no previously-included directories found matching '.git*' 08:52:08 INFO - Successfully installed jsonschema functools32 08:52:08 INFO - Cleaning up... 08:52:08 INFO - Return code: 0 08:52:08 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:52:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:52:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:52:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:52:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:52:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:08 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 0x224e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2346a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24ebb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e9290>, '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 0x24e9b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24ea040>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1489838326.884481-504369783', '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 08:52:08 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 08:52:08 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 08:52:08 INFO - Using env: {'DISPLAY': ':0', 08:52:08 INFO - 'HOME': '/home/cltbld', 08:52:08 INFO - 'LANG': 'en_US.UTF-8', 08:52:08 INFO - 'LANGUAGE': 'en_US:en', 08:52:08 INFO - 'LOGNAME': 'cltbld', 08:52:08 INFO - 'MAIL': '/var/mail/cltbld', 08:52:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:52:08 INFO - 'MOZ_NO_REMOTE': '1', 08:52:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:52:08 INFO - 'NO_EM_RESTART': '1', 08:52:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:52:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:52:08 INFO - 'PWD': '/builds/slave/test', 08:52:08 INFO - 'SHELL': '/bin/bash', 08:52:08 INFO - 'SHLVL': '1', 08:52:08 INFO - 'TERM': 'linux', 08:52:08 INFO - 'TMOUT': '86400', 08:52:08 INFO - 'USER': 'cltbld', 08:52:08 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1489838326.884481-504369783', 08:52:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:52:08 INFO - '_': '/tools/buildbot/bin/python'} 08:52:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:52:08 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:52:08 INFO - Cleaning up... 08:52:08 INFO - Return code: 0 08:52:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:52:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:52:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:52:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:52:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:52:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:08 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 0x224e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2346a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24ebb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e9290>, '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 0x24e9b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24ea040>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1489838326.884481-504369783', '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 08:52:08 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 08:52:08 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 08:52:08 INFO - Using env: {'DISPLAY': ':0', 08:52:08 INFO - 'HOME': '/home/cltbld', 08:52:08 INFO - 'LANG': 'en_US.UTF-8', 08:52:08 INFO - 'LANGUAGE': 'en_US:en', 08:52:08 INFO - 'LOGNAME': 'cltbld', 08:52:08 INFO - 'MAIL': '/var/mail/cltbld', 08:52:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:52:08 INFO - 'MOZ_NO_REMOTE': '1', 08:52:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:52:08 INFO - 'NO_EM_RESTART': '1', 08:52:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:52:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:52:08 INFO - 'PWD': '/builds/slave/test', 08:52:08 INFO - 'SHELL': '/bin/bash', 08:52:08 INFO - 'SHLVL': '1', 08:52:08 INFO - 'TERM': 'linux', 08:52:08 INFO - 'TMOUT': '86400', 08:52:08 INFO - 'USER': 'cltbld', 08:52:08 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1489838326.884481-504369783', 08:52:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:52:08 INFO - '_': '/tools/buildbot/bin/python'} 08:52:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:52:08 INFO - Downloading/unpacking blobuploader==1.2.4 08:52:08 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 08:52:08 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 08:52:08 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 08:52:08 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 08:52:08 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 08:52:08 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 08:52:10 INFO - Downloading blobuploader-1.2.4.tar.gz 08:52:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:52:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:52: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 08:52: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 08:52: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 08:52: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 08:52: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 08:52: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 08:52:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:52:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:52:11 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 08:52:11 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 08:52:11 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 08:52:11 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 08:52:11 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 08:52:11 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 08:52:11 INFO - Downloading docopt-0.6.1.tar.gz 08:52:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:52:11 INFO - Installing collected packages: blobuploader, requests, docopt 08:52:11 INFO - Running setup.py install for blobuploader 08:52:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:52:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:52:11 INFO - Running setup.py install for requests 08:52:12 INFO - Running setup.py install for docopt 08:52:12 INFO - Successfully installed blobuploader requests docopt 08:52:12 INFO - Cleaning up... 08:52:12 INFO - Return code: 0 08:52:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:52:12 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')]} 08:52:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:52:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:12 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')]} 08:52:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:52:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:12 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 0x224e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2346a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24ebb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e9290>, '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 0x24e9b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24ea040>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1489838326.884481-504369783', '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 08:52:12 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 08:52:12 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 08:52:12 INFO - Using env: {'DISPLAY': ':0', 08:52:12 INFO - 'HOME': '/home/cltbld', 08:52:12 INFO - 'LANG': 'en_US.UTF-8', 08:52:12 INFO - 'LANGUAGE': 'en_US:en', 08:52:12 INFO - 'LOGNAME': 'cltbld', 08:52:12 INFO - 'MAIL': '/var/mail/cltbld', 08:52:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:52:12 INFO - 'MOZ_NO_REMOTE': '1', 08:52:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:52:12 INFO - 'NO_EM_RESTART': '1', 08:52:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:52:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:52:12 INFO - 'PWD': '/builds/slave/test', 08:52:12 INFO - 'SHELL': '/bin/bash', 08:52:12 INFO - 'SHLVL': '1', 08:52:12 INFO - 'TERM': 'linux', 08:52:12 INFO - 'TMOUT': '86400', 08:52:12 INFO - 'USER': 'cltbld', 08:52:12 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1489838326.884481-504369783', 08:52:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:52:12 INFO - '_': '/tools/buildbot/bin/python'} 08:52:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:52:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:52:12 INFO - Running setup.py (path:/tmp/pip-P1EiWN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:52:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:52:12 INFO - Running setup.py (path:/tmp/pip-gUbOXI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:52:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:52:12 INFO - Running setup.py (path:/tmp/pip-gW5vC9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:52:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:52:12 INFO - Running setup.py (path:/tmp/pip-H1aK6I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:52:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:52:12 INFO - Running setup.py (path:/tmp/pip-GErKz9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:52:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:52:12 INFO - Running setup.py (path:/tmp/pip-XAp7UR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:52:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:52:12 INFO - Running setup.py (path:/tmp/pip-Q4GTfh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:52:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:52:12 INFO - Running setup.py (path:/tmp/pip-7bD3ho-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:52:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:52:12 INFO - Running setup.py (path:/tmp/pip-L608k4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:52:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:52:13 INFO - Running setup.py (path:/tmp/pip-PMlekp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:52:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:52:13 INFO - Running setup.py (path:/tmp/pip-nuSClh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:52:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:52:13 INFO - Running setup.py (path:/tmp/pip-O67B2I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:52:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:52:13 INFO - Running setup.py (path:/tmp/pip-zmyW_C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:52:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:52:13 INFO - Running setup.py (path:/tmp/pip-NtpAze-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:52:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:52:13 INFO - Running setup.py (path:/tmp/pip-xbRDt7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:52:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:52:13 INFO - Running setup.py (path:/tmp/pip-yWQT35-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:52:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:52:13 INFO - Running setup.py (path:/tmp/pip-PDCPwT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:52:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:52:13 INFO - Running setup.py install for manifestparser 08:52:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:52:13 INFO - Running setup.py install for mozcrash 08:52:13 INFO - Running setup.py install for mozdebug 08:52:13 INFO - Running setup.py install for mozdevice 08:52:14 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:52:14 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:52:14 INFO - Running setup.py install for mozfile 08:52:14 INFO - Running setup.py install for mozhttpd 08:52:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:52:14 INFO - Running setup.py install for mozinfo 08:52:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:52:14 INFO - Running setup.py install for mozInstall 08:52:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:52:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:52:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:52:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:52:14 INFO - Running setup.py install for mozleak 08:52:14 INFO - Running setup.py install for mozlog 08:52:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:52:14 INFO - Running setup.py install for moznetwork 08:52:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:52:14 INFO - Running setup.py install for mozprocess 08:52:14 INFO - Running setup.py install for mozprofile 08:52:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:52:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:52:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:52:14 INFO - Running setup.py install for mozrunner 08:52:15 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:52:15 INFO - Running setup.py install for mozscreenshot 08:52:15 INFO - Running setup.py install for moztest 08:52:15 INFO - Running setup.py install for mozversion 08:52:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:52:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:52:15 INFO - Cleaning up... 08:52:15 INFO - Return code: 0 08:52:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:52: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')]} 08:52:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:52:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52: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')]} 08:52:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:52:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:15 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 0x224e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2346a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24ebb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e9290>, '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 0x24e9b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24ea040>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1489838326.884481-504369783', '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 08:52:15 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 08:52:15 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 08:52:15 INFO - Using env: {'DISPLAY': ':0', 08:52:15 INFO - 'HOME': '/home/cltbld', 08:52:15 INFO - 'LANG': 'en_US.UTF-8', 08:52:15 INFO - 'LANGUAGE': 'en_US:en', 08:52:15 INFO - 'LOGNAME': 'cltbld', 08:52:15 INFO - 'MAIL': '/var/mail/cltbld', 08:52:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:52:15 INFO - 'MOZ_NO_REMOTE': '1', 08:52:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:52:15 INFO - 'NO_EM_RESTART': '1', 08:52:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:52:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:52:15 INFO - 'PWD': '/builds/slave/test', 08:52:15 INFO - 'SHELL': '/bin/bash', 08:52:15 INFO - 'SHLVL': '1', 08:52:15 INFO - 'TERM': 'linux', 08:52:15 INFO - 'TMOUT': '86400', 08:52:15 INFO - 'USER': 'cltbld', 08:52:15 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1489838326.884481-504369783', 08:52:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:52:15 INFO - '_': '/tools/buildbot/bin/python'} 08:52:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:52:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:52:15 INFO - Running setup.py (path:/tmp/pip-nVWXwn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:52:15 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)) 08:52:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:52:15 INFO - Running setup.py (path:/tmp/pip-Iy471p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:52:15 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)) 08:52:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:52:15 INFO - Running setup.py (path:/tmp/pip-uXjDl5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:52:15 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)) 08:52:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:52:15 INFO - Running setup.py (path:/tmp/pip-d5A_Ar-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:52:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:52:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:52:15 INFO - Running setup.py (path:/tmp/pip-olDoTX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:52:15 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)) 08:52:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:52:15 INFO - Running setup.py (path:/tmp/pip-dYBYqQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:52:16 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)) 08:52:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:52:16 INFO - Running setup.py (path:/tmp/pip-K0KqEG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:52:16 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)) 08:52:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:52:16 INFO - Running setup.py (path:/tmp/pip-H0GlMi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:52:16 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)) 08:52:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:52:16 INFO - Running setup.py (path:/tmp/pip-uEYHfN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:52:16 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)) 08:52:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:52:16 INFO - Running setup.py (path:/tmp/pip-jcN09k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:52:16 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)) 08:52:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:52:16 INFO - Running setup.py (path:/tmp/pip-ognbD3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:52:16 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)) 08:52:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:52:16 INFO - Running setup.py (path:/tmp/pip-uNDHT4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:52:16 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)) 08:52:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:52:16 INFO - Running setup.py (path:/tmp/pip-Px4PKz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:52:16 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)) 08:52:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:52:16 INFO - Running setup.py (path:/tmp/pip-jotSdm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:52:16 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)) 08:52:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:52:16 INFO - Running setup.py (path:/tmp/pip-LoKKXE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:52:16 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)) 08:52:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:52:16 INFO - Running setup.py (path:/tmp/pip-vdc5Rv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:52:16 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)) 08:52:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:52:16 INFO - Running setup.py (path:/tmp/pip-sRG7K0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:52:16 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)) 08:52:16 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)) 08:52:16 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)) 08:52:16 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)) 08:52:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:52:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:52:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:52:16 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 08:52:16 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 08:52:16 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 08:52:16 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 08:52:16 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 08:52:16 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 08:52:18 INFO - Downloading blessings-1.6.tar.gz 08:52:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:52:18 INFO - Installing collected packages: blessings 08:52:18 INFO - Running setup.py install for blessings 08:52:19 INFO - Successfully installed blessings 08:52:19 INFO - Cleaning up... 08:52:19 INFO - Return code: 0 08:52:19 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:52:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:52:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:52:19 INFO - Reading from file tmpfile_stdout 08:52:19 INFO - Current package versions: 08:52:19 INFO - argparse == 1.2.1 08:52:19 INFO - blessings == 1.6 08:52:19 INFO - blobuploader == 1.2.4 08:52:19 INFO - docopt == 0.6.1 08:52:19 INFO - functools32 == 3.2.3-2 08:52:19 INFO - jsonschema == 2.5.1 08:52:19 INFO - manifestparser == 1.1 08:52:19 INFO - mozInstall == 1.12 08:52:19 INFO - mozcrash == 1.0 08:52:19 INFO - mozdebug == 0.1 08:52:19 INFO - mozdevice == 0.49 08:52:19 INFO - mozfile == 1.2 08:52:19 INFO - mozhttpd == 0.7 08:52:19 INFO - mozinfo == 0.9 08:52:19 INFO - mozleak == 0.1 08:52:19 INFO - mozlog == 3.4 08:52:19 INFO - moznetwork == 0.27 08:52:19 INFO - mozprocess == 0.25 08:52:19 INFO - mozprofile == 0.28 08:52:19 INFO - mozrunner == 6.13 08:52:19 INFO - mozscreenshot == 0.1 08:52:19 INFO - mozsystemmonitor == 0.3 08:52:19 INFO - moztest == 0.8 08:52:19 INFO - mozversion == 1.4 08:52:19 INFO - psutil == 3.1.1 08:52:19 INFO - requests == 1.2.3 08:52:19 INFO - wsgiref == 0.1.2 08:52:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:52: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')]} 08:52:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:52:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52: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')]} 08:52:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:52:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:19 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 0x224e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2346a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24ebb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e9290>, '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 0x24e9b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24ea040>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1489838326.884481-504369783', '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 08:52:19 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 08:52:19 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 08:52:19 INFO - Using env: {'DISPLAY': ':0', 08:52:19 INFO - 'HOME': '/home/cltbld', 08:52:19 INFO - 'LANG': 'en_US.UTF-8', 08:52:19 INFO - 'LANGUAGE': 'en_US:en', 08:52:19 INFO - 'LOGNAME': 'cltbld', 08:52:19 INFO - 'MAIL': '/var/mail/cltbld', 08:52:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:52:19 INFO - 'MOZ_NO_REMOTE': '1', 08:52:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:52:19 INFO - 'NO_EM_RESTART': '1', 08:52:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:52:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:52:19 INFO - 'PWD': '/builds/slave/test', 08:52:19 INFO - 'SHELL': '/bin/bash', 08:52:19 INFO - 'SHLVL': '1', 08:52:19 INFO - 'TERM': 'linux', 08:52:19 INFO - 'TMOUT': '86400', 08:52:19 INFO - 'USER': 'cltbld', 08:52:19 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1489838326.884481-504369783', 08:52:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:52:19 INFO - '_': '/tools/buildbot/bin/python'} 08:52:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:52:19 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)) 08:52:19 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)) 08:52:19 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)) 08:52:19 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)) 08:52:19 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)) 08:52:19 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)) 08:52:19 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)) 08:52:19 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)) 08:52:19 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)) 08:52:19 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)) 08:52:19 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)) 08:52:19 INFO - Cleaning up... 08:52:19 INFO - Return code: 0 08:52:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:52: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')]} 08:52:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:52:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52: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')]} 08:52:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:52:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:52:19 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 0x224e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2346a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24ebb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e9290>, '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 0x24e9b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24ea040>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1489838326.884481-504369783', '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 08:52:19 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 08:52:19 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 08:52:19 INFO - Using env: {'DISPLAY': ':0', 08:52:19 INFO - 'HOME': '/home/cltbld', 08:52:19 INFO - 'LANG': 'en_US.UTF-8', 08:52:19 INFO - 'LANGUAGE': 'en_US:en', 08:52:19 INFO - 'LOGNAME': 'cltbld', 08:52:19 INFO - 'MAIL': '/var/mail/cltbld', 08:52:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:52:19 INFO - 'MOZ_NO_REMOTE': '1', 08:52:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:52:19 INFO - 'NO_EM_RESTART': '1', 08:52:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:52:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:52:19 INFO - 'PWD': '/builds/slave/test', 08:52:19 INFO - 'SHELL': '/bin/bash', 08:52:19 INFO - 'SHLVL': '1', 08:52:19 INFO - 'TERM': 'linux', 08:52:19 INFO - 'TMOUT': '86400', 08:52:19 INFO - 'USER': 'cltbld', 08:52:19 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1489838326.884481-504369783', 08:52:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:52:19 INFO - '_': '/tools/buildbot/bin/python'} 08:52:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:52:19 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:52:19 INFO - Cleaning up... 08:52:19 INFO - Return code: 0 08:52:19 INFO - Running post-action listener: _resource_record_post_action 08:52:19 INFO - Running post-action listener: _start_resource_monitoring 08:52:19 INFO - Starting resource monitoring. 08:52:19 INFO - [mozharness: 2017-03-18 15:52:19.813090Z] Finished create-virtualenv step (success) 08:52:19 INFO - [mozharness: 2017-03-18 15:52:19.813608Z] Running install step. 08:52:19 INFO - Running pre-action listener: _resource_record_pre_action 08:52:19 INFO - Running main action method: install 08:52:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:52:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:52:20 INFO - Reading from file tmpfile_stdout 08:52:20 INFO - Detecting whether we're running mozinstall >=1.0... 08:52:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:52:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:52:20 INFO - Reading from file tmpfile_stdout 08:52:20 INFO - Output received: 08:52:20 INFO - Usage: mozinstall [options] installer 08:52:20 INFO - Options: 08:52:20 INFO - -h, --help show this help message and exit 08:52:20 INFO - -d DEST, --destination=DEST 08:52:20 INFO - Directory to install application into. [default: 08:52:20 INFO - "/builds/slave/test"] 08:52:20 INFO - --app=APP Application being installed. [default: firefox] 08:52:20 INFO - mkdir: /builds/slave/test/build/application 08:52:20 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'] 08:52:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 08:52:35 INFO - Reading from file tmpfile_stdout 08:52:35 INFO - Output received: 08:52:35 INFO - /builds/slave/test/build/application/firefox/firefox 08:52:35 INFO - Running post-action listener: _resource_record_post_action 08:52:35 INFO - [mozharness: 2017-03-18 15:52:35.610980Z] Finished install step (success) 08:52:35 INFO - [mozharness: 2017-03-18 15:52:35.611255Z] Running run-tests step. 08:52:35 INFO - Running pre-action listener: _resource_record_pre_action 08:52:35 INFO - Running main action method: run_tests 08:52:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:52:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:52:35 INFO - Python 2.7.3 08:52:35 INFO - Return code: 0 08:52:35 INFO - grabbing minidump binary from tooltool 08:52:35 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')]} 08:52:35 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 0x24e9290>, '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 0x24e9b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24ea040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:52:35 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 08:52:35 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 08:52:35 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 08:52:35 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:52:35 INFO - Return code: 0 08:52:35 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:52:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:52:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:52:35 INFO - ENV: RUST_BACKTRACE is now 1 08:52:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:52:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:52:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TEKF6SNBREeQZgQt-7oloQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-072', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 08:52:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/TEKF6SNBREeQZgQt-7oloQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-072 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 08:52:35 INFO - Using env: {'DISPLAY': ':0', 08:52:35 INFO - 'HOME': '/home/cltbld', 08:52:35 INFO - 'LANG': 'en_US.UTF-8', 08:52:35 INFO - 'LANGUAGE': 'en_US:en', 08:52:35 INFO - 'LOGNAME': 'cltbld', 08:52:35 INFO - 'MAIL': '/var/mail/cltbld', 08:52:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:52:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:52:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:52:35 INFO - 'MOZ_NO_REMOTE': '1', 08:52:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:52:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:52:35 INFO - 'NO_EM_RESTART': '1', 08:52:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:52:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:52:35 INFO - 'PWD': '/builds/slave/test', 08:52:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:52:35 INFO - 'RUST_BACKTRACE': '1', 08:52:35 INFO - 'SHELL': '/bin/bash', 08:52:35 INFO - 'SHLVL': '1', 08:52:35 INFO - 'TERM': 'linux', 08:52:35 INFO - 'TMOUT': '86400', 08:52:35 INFO - 'USER': 'cltbld', 08:52:35 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1489838326.884481-504369783', 08:52:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:52:35 INFO - '_': '/tools/buildbot/bin/python'} 08:52:35 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TEKF6SNBREeQZgQt-7oloQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-072', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 08:52:35 INFO - mozversion application_buildid: 20170318152521 08:52:35 INFO - mozversion application_changeset: e1576dd8bd9d3a4ca418cf347133b8a4957ddeca 08:52:35 INFO - mozversion application_display_name: Nightly 08:52:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:52:35 INFO - mozversion application_name: Firefox 08:52:35 INFO - mozversion application_remotingname: firefox 08:52:35 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:52:35 INFO - mozversion application_vendor: Mozilla 08:52:35 INFO - mozversion application_version: 55.0a1 08:52:35 INFO - mozversion platform_buildid: 20170318152521 08:52:35 INFO - mozversion platform_changeset: e1576dd8bd9d3a4ca418cf347133b8a4957ddeca 08:52:35 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:52:35 INFO - mozversion platform_version: 55.0a1 08:52:35 INFO - using testdate: 1489852355 08:52:35 INFO - actual date: 1489852355 08:52:35 INFO - starting webserver on 'localhost:55012' 08:52:35 INFO - SUITE-START | Running 2 tests 08:52:35 INFO - TEST-START | dromaeo_css 08:52:35 INFO - operating with platform_type : linux_ 08:52:35 INFO - Initialising browser for dromaeo_css test... 08:52:35 INFO - TEST-INFO | started process 4139 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpuWrdpW/profile http://localhost:55012/getInfo.html) 08:52:40 INFO - PID 4139 | __metrics Screen width/height:1600/1200 08:52:40 INFO - PID 4139 | colorDepth:24 08:52:40 INFO - PID 4139 | Browser inner width/height: 1024/697 08:52:40 INFO - PID 4139 | __metrics 08:52:41 ERROR - PID 4139 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3350: TypeError: this.tabs is undefined 08:52:42 INFO - TEST-INFO | 4139: exit 0 08:52:42 INFO - Browser initialized. 08:52:42 INFO - Running cycle 1/1 for dromaeo_css test... 08:52:42 INFO - TEST-INFO | started process 4268 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpuWrdpW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:52:54 INFO - PID 4268 | RSS: Main: 155217920 08:52:54 INFO - PID 4268 | 08:55:58 INFO - PID 4268 | Cycle 1(1): loaded http://localhost:55012/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55012/tests/dromaeo/cssquery-ext.html) 08:56:01 INFO - PID 4268 | RSS: Main: 164696064 08:56:01 INFO - PID 4268 | 08:57:43 INFO - PID 4268 | ************************* 08:57:43 INFO - PID 4268 | A coding exception was thrown and uncaught in a Task. 08:57:43 INFO - PID 4268 | 08:57:43 ERROR - PID 4268 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:57:43 INFO - PID 4268 | Full stack: 08:57:43 INFO - PID 4268 | ************************* 08:59:03 INFO - PID 4268 | Cycle 1(1): loaded http://localhost:55012/tests/dromaeo/cssquery-ext.html (next: http://localhost:55012/tests/dromaeo/cssquery-jquery.html) 08:59:04 INFO - PID 4268 | RSS: Main: 161734656 08:59:04 INFO - PID 4268 | 09:02:06 INFO - PID 4268 | Cycle 1(1): loaded http://localhost:55012/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55012/tests/dromaeo/cssquery-mootools.html) 09:02:06 INFO - PID 4268 | RSS: Main: 170950656 09:02:06 INFO - PID 4268 | 09:05:08 INFO - PID 4268 | Cycle 1(1): loaded http://localhost:55012/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55012/tests/dromaeo/cssquery-prototype.html) 09:05:09 INFO - PID 4268 | RSS: Main: 162512896 09:05:09 INFO - PID 4268 | 09:08:12 INFO - PID 4268 | Cycle 1(1): loaded http://localhost:55012/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55012/tests/dromaeo/cssquery-yui.html) 09:08:13 INFO - PID 4268 | RSS: Main: 168423424 09:08:13 INFO - PID 4268 | 09:11:16 INFO - PID 4268 | Cycle 1(1): loaded http://localhost:55012/tests/dromaeo/cssquery-yui.html (next: http://localhost:55012/tests/dromaeo/cssquery-dojo.html) 09:11:16 INFO - PID 4268 | RSS: Main: 161546240 09:11:16 INFO - PID 4268 | 09:11:16 INFO - PID 4268 | __start_tp_report 09:11:16 INFO - PID 4268 | _x_x_mozilla_page_load 09:11:16 INFO - PID 4268 | _x_x_mozilla_page_load_details 09:11:16 INFO - PID 4268 | |i|pagename|runs| 09:11:16 INFO - PID 4268 | |0;dojo.html;2678.892844286229;2689.757921787039;2701.6082668013137;2711.3763834318106;2721.863906804658;4984.87537811554;5030.446650868402;5057.595392368611;5093.745312734358;5103.055934652088;5003.47463516331;5076.79692812288;5103.362079740024;5140.7172605506685;5158.509941555546;4593.012502312003;4631.698939568934;4653.813847446098;4679.368285281477;4700.600448961843;4674.976625116869;4683.555062269079;4702.130105930397;4730.101280756645;4753.928691069636;4563.977180114111;4596.333531637893;4596.54034596541;4637.095766325568;4643.326717625959;4626.398568186148;4637.5594318540025;4642.79107440166;4663.067386522709;4676.392069031004;4557.0885822835235;4588.908221835544;4590.311453282001;4609.516000819933;4663.953360466386;4893.510648935114;4942.382202224722;5005.374328208974;5020.246962955549;5027.547520723116;5486.91769623456;5491.395946445888;5502.174673798922;5518.668879867221;5559.082751346023;5527.39198688144;5539.944600553983;5540.63985840919;5556.472135147195;5572.665640061568;4915.4347250065985;4959.603231741452;4960.950390496121;5171.862190318124;5214.270002199695;5423.755930983115;5429.9411614734745;5457.208704737791;5461.180822876521;5482.698451585121;5179.81870634526;5417.322834645669;5454.8909021819345;5464.535514481234;5479.561635069185;5461.481159289861;5491.36849262332;5492;5500.642458240201;5509.669419834822;5464.808731694451;5469.945300546943;5470.425605311385;5489.451054894558;5549.278593782863;5307.65500242483;5378.220158077057;5428.538574221236;5476.561875050066;5494.066008778517;4614.215583350839;4624.1445332613575;4624.5375462453485;4655.4180727409075;4674;3973.8410463581713;3974.0462289050997;3995.360742281221;3998.460207871958;4025.275450418953;4623.214053610895;4686.297055441642;4727.929081063787;4731.95268047322;4746.572808447257;5487.122060470306;5498.092814685532;5498.835034948958;5523.061079616475;5523.171524271392;4688.296755486705;4718.952810471851;4727.763611819395;4733.266343716729;4763.237881938873;4823.5658790709;4883.121038213085;4884;4916.311716359713;4944.728039957765;5193.246979187921;5203.297554830049;5225.973870130777;5237.135872580981;5254.9474505255985;5380.031594312904;5447.455254474521;5548.167774833807;5549.084401073777;5559.11054231321;5152.819651312186;5154.742262886915;5207.531322181022;5207.6875387476875;5209.765560549708;5222.138347172674;5332.973335133454;5340.252364668871;5375.381831089318;5413.7834486620095;5426.348838139448;5437.211604317273;5443.455654434525;5444.482774136451;5448.155535891943;5218.921716174336;5250.606204534752;5255.185446255837;5256.947430525645;5261.421243663271;5223.3209682741;5235.685858848481;5242.554382877498;5254.054270231395;5254.921176182437;5175.094358487175;5187.273781670645;5199.038177937094;5249.89500209986;5258.50044245796;5358.035553600389;5374.570034397317;5376.731163441734;5412.891742165056;5424.538914192338;5172.275881376686;5242.5281724644965;5243.187305967581;5251.606129540376;5304.124819404909 09:11:16 INFO - PID 4268 | |1;ext.html;688.4182865478671;705.4920457270766;779.4543819326469;790.332169316927;795.8607243732349;34066.31867362651;35153.47269790955;35175.296494070106;35206.295874082534;35294.117647058774;31543.526847097255;31885.68114318856;31929.68070319302;32000.519992200076;32007.67992320076;6149.508039356863;6292.4966002719675;6307.211598550181;8595.527246001468;8784.07767184446;9097.454152750823;9145.588448519798;9238.86141707875;9304.348695591312;9309.394889332172;9383.530823458834;9563.856542151872;9567.282453815957;9584.616615335379;9594.28042896782;9409.059094090604;9474.38416502925;9478.668246611405;9492.098250666175;9523.619055237818;9003.414778039472;9173.403728757676;9193.448393096436;9195.21840643546;9198.63205471787;33907.491387629205;33968.320633587435;34111.14722131967;34138;34179.487307690404;3416.197193659488;3457.170279133015;3473.3053389321985;3518.0501264658656;3530.435130379127;24648.260552183463;24730.876345618337;24903.87548062266;25003.749962500508;25305.36736581582;3323.6510166432417;3386.475096360068;3420.6066302375298;3438.1060924159647;3448.1724386147143;161193;162562.9999999988;162610.9999999988;162708;162956;32469.51295730566;33150;37513.24973500515;42009.78995105005;42251.33847561304;42786.78606606947;42926;43071.35392969108;43133;43376.13247735028;12358.814617780741;12547.811782823264;12550.372481375894;12560.937195313965;12641.683957900943;9667.758306042406;9723.076307750895;9727.319087663796;9729.854052189223;9785.755356116011;13256.337183140804;13291.867081329256;13394.397252123677;13463.461461541428;13668.52160174389;5411.134218525017;5497.945020549823;5552.944470555323;5580.888382232414;5590.546457920888;851.3529717414809;887.3389324952951;984.5175863826673;1005.4620777883944;1060.3213943076437;20312.796872031387;21677.34967950964;22169.22407715722;22231.886666066574;22380.55238895213;1089.9945500272606;1096.1888202729922;1098.4288170151476;1106.247751528952;1118.8769235384102;10727.249092563601;11329.886701133048;11551.249168804;13549.25479098659;14453.85546144546;237230;237918;238140;238629;238935;45991.540084598724;46452.5354746455;46553.30170047452;46694.53305466902;46850.76574617105;1189.8929096381366;1237.127824883455;1241.1684171605045;1241.453760345445;1328.9601311960657;24219.496654986113;24459.388515286548;25555.36111597225;25783.355416114748;25812.22563323103;31699.683003169674;31807.681923180473;31908.202294941882;31953.68046319507;32064.198395040312;224540;226244;226456;227451;227764;27217.455650887267;27875.303117422227;27949.30126746766;27979;28585.71414285914;31860.362792743552;32226.35547289088;32908.341833162725;32956.67043329536;33006.504902426;29833.850830746575;30147.397052058397;30255.394892101594;30290;31284;29719.25701857471;29906.85046574753;30236.84881575578;30341.848290758404;30558.08325750231;29837.70162298349;30255.09234722962;30271.848640756656;30512.694873051874;30686.539701904934;22757.544849102593;26233.868830655723;26343;26461.60307595349;27384;21898.67151992753;21909;22121.778782212616;22243.77756222417;22252.777472225716 09:11:16 INFO - PID 4268 | |2;jquery.html;390.14558117722186;390.6874500399683;395.6656625151746;403.2197697455423;411.34801339876293;13232.801507977363;17919.91040044801;18049;18739.156737946792;18780.436586902397;16807.66384672306;16842.83157168431;17027.233774480148;18395.148751943438;19052.61894762104;5005.674631148972;5672.602917795756;5761.30864296285;5776.826695199151;5884.941150588482;5607.719614019303;5746.626469279484;5758.107493338539;5759.740811663485;5769.048107062351;5749.482546570808;5774.913376299359;6709.228438729536;7031.1211098612675;7072.717091316367;6668.399844014062;7017.8596428071405;7035.9296407036045;7044.718211271543;7061.929380706179;6872.896906546406;6924;6924.307569243086;6932.618705971169;6932.72269109235;24406.145784897442;24966.251012469656;25143.87428062866;25455.363615909566;25760.09839655603;2334.8365614407;2391.330427480308;2424.5756992526412;2431.9148829790875;2467.716212635552;14319.928400358036;15008.774868376982;15387.538373848802;15411.614709632237;15681.451149209723;2052.332991777678;2057.7324947756847;2071.9378418647466;2093.110428068065;2111.3349294972036;87408;91188;91379;93626.5318673409;93747;18316.63366732658;18365.54086147857;18459.446216613524;18484.630307393778;18596.44210673682;26341;33908;34304;34507;34753.30493390118;8210.343172546181;8390.664373424994;8479.915200847912;8660.39377243587;8673.696420625249;6713.865722685519;6769.289224631441;6914.51598388108;6917;6920.757773477904;9051.321150913707;9113.954430227806;9288.442693438416;9368.95315523418;9427.104425079606;3539.805310707936;3722.4974628425357;3780.7353485256317;3878.301905657009;3902.180542086137;820.5076953827655;834.7328854766416;840.1976112812249;846.9915300847036;873.6549063120032;19294.614107718047;20389.286374976804;20583;20676.896615517126;20778.792212077988;831.4013909984922;842.0653075086649;848.2154007543121;858.4934888415739;870.7735988642999;10512.21158413122;11172.720681983015;11206.887931120584;11219.1585631078;11264.605738799266;454460;455947;459174;464948;465823;82803;91950.54024729834;93088;94788;95167;1098.4068602954476;1112.6495154026456;1155.5551112821456;1167.497975870384;1168.9941550292194;17478.388256411472;17775.911120444314;18517.44447666572;18602.720959185353;18740.90629546898;41132;42343.74766387808;42713;42950.35574466448;43385.13229735451;54398;54669.72665136649;55142.44857551373;55621.72189139028;56686.7165664169;10495;10506.947465262625;10517.368957862553;10599.099076578577;10991.505382257657;12334.81497777552;12825.679358015752;12873.03452241067;13115.147515411849;13181.80227296572;10256.58973641046;10458;10876.347419154876;11373.431328433711;11543.8268425972;10727.839082413613;10809.459527023775;10816.56733730642;11210.607628732956;11335.206535542433;11202.66392008241;11332.263402878787;11344.092472602008;11590.304581725124;11637.243579167327;10258.743531411774;10836.72428789963;10865.18511111654;10972.506237219504;11185.944070279596;10795.838062429228;10954.397508137128;11016.17378696585;11196.272242304223;11222.250548615315 09:11:16 INFO - PID 4268 | |3;mootools.html;1400.9803921568628;1415.384307826095;1438.1946110178294;1447.0738727214577;1512.8411516790736;7746.225377462251;7963.840723185533;8998.820023599525;9398.953005234982;9406.341556091067;8927;9295.163435290822;9365.34442589019;9386.624535018607;9388.061193880609;1979.3962841333423;2011.9094640741127;2020.0303854150018;2021.1006102284489;2023.1199428248758;1947.4936516505668;1988.204718112752;1989.2440872468442;2001.979980200194;2046.6213750456138;1962.2543433495323;1964.6856502959529;1966.2331690640663;1970.980290197101;1989.2440872468442;1887.6979683250752;1896.7344571759966;1904.228787341129;1908.5610309628862;1910.4555201767498;1895.9431217063511;1897.3359324236408;1913.23470611755;1916;1964.3205059673917;9120.498372589505;9176;9204.125608067223;9212.585433655502;9213.493257870814;4488.3940668010055;4688.531146885284;4703;4703.106409782131;4705.247160454311;2554.961675574868;2706.8917243310243;2764.2398340456334;2772.9306767330977;2782.095818858859;1006.1699098243905;1031.381171297223;1032.2774058159318;1093.5352475198008;1101.0475938313382;13473.124246924017;13539.255340956244;13552.59342219735;13571.439284482312;13628.795568066585;3743.550773907094;3851.5185601799776;3992.680585553207;4059.6955228357992;4066.6543343816106;11036.061934735477;11081.667549973352;11144.108471322275;11166.218364714392;11193.440327983568;4641.2109941310055;4645.767711614406;4650.651201159926;4652.581267685924;4653.86038418848;3650.1969566695284;3656.1956369598647;4204.096119334357;4302.397664326997;4312.482502099768;3351.4972754086593;3403.5235067090625;3422.931541369159;3491.825408729553;3503.054175372635;933.9579718912665;949.6960972488876;959.9424034557949;965.0012237334325;970.1656575345197;727.7998550398682;728.2098922668963;745.1691364128957;748.4648476339444;762.8893810397461;4946.08497427977;5000.899982000413;5092.185250359925;5142.845714628567;5196.220566914993;846.8687353460224;861.810401711635;869.1352104655781;869.9173578510031;870.8867847179826;3153.195935036551;3196.7282780963415;3229.6285927118684;3234.2884565395575;3322.900312990614;11122.277051991594;11468.13988950832;11690.415479226005;11700.941495292469;11731.88268117325;11338.60314888975;11451.828222576669;11562.364069976233;11591.76816463666;11596.130290228266;8009.679612815422;8012.278894899487;8101.675932962616;8108.391870609727;8109.189081091844;5122.385313762373;5193.480651934777;5209.947900520946;5259.710715910662;5281.735913204401;10219.386836789816;10241.334313269614;10245.692629221136;10385.584576616851;10494.632687855888;10377.377357358582;10561.63034293796;10585.841212381974;10589.841152382874;10622.628208012682;7838.255365740246;7846.411519136202;7905.881411778713;7970.880436793337;8003.19968003195;8791;8877.467351958903;8888.33337499703;8897.911020889967;8930.955345223232;7804.6878124874365;7932.801679958047;7939.0870049945;8076.313513351431;8161.632726527202;7803.141654418123;7814.257645523666;7851.411144164096;7875.684972601031;7977.321927636216;6782.728690852508;6805.4895882808;6826.488013398915;6892.896606550806;6924.619145947021;4654.697444666086;4718.31584420264;4756.500567440507;4781.306710527024;4870.05034018363;4598.402207712976;4631;4651.627869770478;4731.172044892061;4756.381670382828 09:11:16 INFO - PID 4268 | |4;prototype.html;362.3513910100919;367.23798118903284;369.1841031320781;370.65343903450275;381.8854343696892;5284.920726189105;5295.391030031547;5307.70807605582;5389.326334208224;5505.944940550593;5223.320968274119;5276.076686579852;5290.894182116365;5302.549283310914;5325.946740532593;4377.956220437794;4417.690761646686;4553.453585569736;4564.360989461478;4579.908401831962;4544.9091018179615;4545.409096817409;4559.316102584606;4562.566556177175;4568.926302318969;4570.611498022672;4583.541645835407;4589.472210695762;4591.242444996572;4598.333241679954;4460.79926403311;4536.614387777043;4546.090781843644;4575.290829921368;4597.42532183477;4364.061726728737;4373.387725718402;4381.07997320563;4386.429764130675;4403.691741578091;5208.192730126836;5223;5239.79040838366;5239.869003274948;5253.553447956928;5239.816606418757;5378.704171270579;5380.24676545284;5407.837764867021;5414.810481633163;5699;5709.685967271798;5711.400302968212;5713;5715.056880660595;4872.171730805771;4882.145624515695;4899.779509922062;4907.656464047518;4922.458529561745;5742.454466825645;5768.3654797972185;5884.499817515559;6004.18943442634;6047.15339852421;4759.904801903943;4815.951840481621;4816.95183048172;4837.637177211724;4840.177169881129;4896.387951506062;4942.406911170682;4945.1346014448045;4946.901061978812;4958.851234462972;5110.71891045996;5200.557952573998;5226.947730522722;5228.268417082546;5252.632315737938;5625.268715067097;5640.830775076754;5647.548196144298;5657.915131273035;5665.518430933417;1797.2631221199244;1820.3173809821317;1821.2623887325653;1824.3888297420247;1831.624516974017;1421.7914772443341;1479.9334029968677;1495.7606782914681;1501.6996600679909;1525.59571713496;4868.172410690191;4876.731779752076;4895.583875370633;4913.1893237616105;4925.458199598042;5748.223989761413;5783.479486846204;5826.970865145647;5831.795887144014;5833.79581714638;4705.8352957647;4905.264210368473;4930.211166213388;4940.061388336277;4950.43070046942;3057.4343746406985;3067.1412004638737;3075.815451072943;3078.6921307869034;3080.92297692555;5247.475252474798;5396.109641909041;5400.729963501809;5416.647917885325;5429.104197807317;5901.970490147608;5906.40935906406;5948.434898684618;5958.480587450173;5978.671173085435;5417.945820541823;5422.322209723785;5460;5461.890762184655;5473.357992602283;5656.321241451053;5742.483176514133;5771.286426437712;5775.595708300379;5797.796837202005;5724.599278050497;5724.685142317213;5735.340435849931;5746.482816546531;5787.826365209051;5205.479452054764;5240.768419421509;5342.6260161788305;5384.730763461889;5459.08931873841;5012.8496145115705;5038.470960549236;5053.898922021613;5059.772310245974;5064.848054558369;5601.831945041656;5751.626144300607;5756.366799652119;5783.508401785895;5799.130130480293;5558.388577256418;5591.608587398843;5594.440555944373;5607.663540187602;5618.438156184349;5565.415631358649;5579.16312553104;5590.832275031756;5592.832215033555;5603.299587551556;5310.628256022196;5456.263404440349;5484;5495.835124946258;5519.586031047607;5601.823617040304;5622.297212848394;5629.211910332475;5641.26663533738;5648.519875810603;5249.396319423316;5421.864453388539;5452.5365343946205;5466.671999680032;5467.45325467466 09:11:16 INFO - PID 4268 | |5;yui.html;645.1226332188226;648.3419329380677;703.6692754405431;751.5040073551456;789.6486063701653;12961.935190324048;12984.480620775157;13144.47422103117;13706.383212755412;13862;12766.297853618047;12909.031822613295;12982.156159849603;13026.869731302684;13727.725445491109;801.6512816924657;817.6770175780578;828.5235989306143;838.6393850644221;838.8741688746676;817.6811043692935;820.1757233979841;825.47582285249;826.8759686047081;838.5262326785347;864.8962124545063;865.3553102938288;867.6659486097834;868.1231955724701;869.0787764969153;860.6557377049168;860.746079906426;864.1703964194318;866.9653213871437;867.332154241237;628.767356078254;629.3046183966742;633.4077637409038;633.7465013994394;635.2757856044113;13439.66400839977;13584.456621735217;13620.250886201255;13709.17744935297;15097.924510377377;12331.876681233163;12333.136680432373;13082.542111026163;13544;13564.593062208149;9857.704268871945;9867.802643947081;10338.22463315247;10622.78754424915;10738.570457181704;3256.4138455078078;3277.3772983133126;3367.7305815534696;3423.7945723256435;3432.9656703433147;65785.67107164481;67235;67559.66220168868;68419.65790171067;69901.30098698998;10859.78280434387;10968.945155274172;11077.612283570037;11152.498137583829;11322.490487928064;21182;21258.362249132242;21376;21627.134914603554;21696.78303216979;7012.509124361246;7090.184628767654;7135.143782746104;7216.927830721731;7367.81580460482;3523.1720545671747;3577.159367548624;3621.8370173342264;3626.202235508184;3632.8365223565;2795.8182718123257;2822.520171570838;2840.573913912888;2848;2854.300696329371;466.37272867992493;472.4283616823613;478.5740690785204;484.48886424821444;486.85394381685353;582.4728620598365;615.6398506873529;628.13003989474;637.0698779781478;638.0779773227734;8895.396451172603;9265.814683706289;9466.621335146654;9521.476318802394;9529.618815247313;598.1207624791549;620.454000479575;621.8205190806285;625.1497962770628;629.2794750011211;4115.609017143366;4310.267254566731;4393.934090988638;4416.580424859633;4481.932771008437;50009.49990500121;55514.16728749072;61743;65601;65692.34307656958;54667.72666136644;55089.72455137699;55166.448335516936;55498.44501555013;55649.721751390985;50612;51551.99999999925;51587.742061290206;52034.21948670773;52344.47655523472;7988.201179881965;8408.957955210184;8443.873341899754;8490.904739109612;8552.57237138153;34812.65187348094;34882;35530.644693552735;35696.10759731028;35716.82141589275;40914.795426022676;42546.361804572334;43304.78347608242;46754.29868551906;47715.522844771105;11726.765464690829;12288.385580721108;12606.810897836356;12837.293948832903;13148.276844773629;13076.411561479566;13875.86124138786;13991.650208744863;14257.928710356382;14588.197649128975;12066.057975353062;12790.296533690736;12885.935570322088;13086.672833179247;13442.731145377233;12000.459979300778;12381.62855114348;12572.811407828707;12588.244705317711;13084.869151308745;11483.655490335303;12084.214526055779;12158.45286962071;12237.938810305892;12563.937180314406;10052.195824333889;10149.898501014895;10480.842787358348;10496.323437523688;11182.66452006441;9362.76593085151;9683.467409292556;10825.350478971286;10834.837477438003;11042.227044106836 09:11:16 INFO - PID 4268 | __end_tp_report 09:11:16 INFO - PID 4268 | __start_cc_report 09:11:16 INFO - PID 4268 | _x_x_mozilla_cycle_collect,395 09:11:16 INFO - PID 4268 | __end_cc_report 09:11:16 INFO - PID 4268 | __startTimestamp1489853476332__endTimestamp 09:11:16 INFO - PID 4268 | 09:11:16 INFO - PID 4268 | ------- Summary: start ------- 09:11:16 INFO - PID 4268 | Number of tests: 6 09:11:16 INFO - PID 4268 | 09:11:16 INFO - PID 4268 | [#0] dojo.html Cycles:165 Average:5026.56 Median:5203.30 stddev:556.42 (10.7%) stddev-sans-first:526.77 09:11:16 INFO - PID 4268 | Values: 2678.9 2689.8 2701.6 2711.4 2721.9 4984.9 5030.4 5057.6 5093.7 5103.1 5003.5 5076.8 5103.4 5140.7 5158.5 4593.0 4631.7 4653.8 4679.4 4700.6 4675.0 4683.6 4702.1 4730.1 4753.9 4564.0 4596.3 4596.5 4637.1 4643.3 4626.4 4637.6 4642.8 4663.1 4676.4 4557.1 4588.9 4590.3 4609.5 4664.0 4893.5 4942.4 5005.4 5020.2 5027.5 5486.9 5491.4 5502.2 5518.7 5559.1 5527.4 5539.9 5540.6 5556.5 5572.7 4915.4 4959.6 4961.0 5171.9 5214.3 5423.8 5429.9 5457.2 5461.2 5482.7 5179.8 5417.3 5454.9 5464.5 5479.6 5461.5 5491.4 5492.0 5500.6 5509.7 5464.8 5469.9 5470.4 5489.5 5549.3 5307.7 5378.2 5428.5 5476.6 5494.1 4614.2 4624.1 4624.5 4655.4 4674.0 3973.8 3974.0 3995.4 3998.5 4025.3 4623.2 4686.3 4727.9 4732.0 4746.6 5487.1 5498.1 5498.8 5523.1 5523.2 4688.3 4719.0 4727.8 4733.3 4763.2 4823.6 4883.1 4884.0 4916.3 4944.7 5193.2 5203.3 5226.0 5237.1 5254.9 5380.0 5447.5 5548.2 5549.1 5559.1 5152.8 5154.7 5207.5 5207.7 5209.8 5222.1 5333.0 5340.3 5375.4 5413.8 5426.3 5437.2 5443.5 5444.5 5448.2 5218.9 5250.6 5255.2 5256.9 5261.4 5223.3 5235.7 5242.6 5254.1 5254.9 5175.1 5187.3 5199.0 5249.9 5258.5 5358.0 5374.6 5376.7 5412.9 5424.5 5172.3 5242.5 5243.2 5251.6 5304.1 09:11:16 INFO - PID 4268 | 09:11:16 INFO - PID 4268 | [#1] ext.html Cycles:180 Average:35196.96 Median:31873.02 stddev:54972.18 (172.5%) stddev-sans-first:55065.33 09:11:16 INFO - PID 4268 | Values: 688.4 705.5 779.5 790.3 795.9 34066.3 35153.5 35175.3 35206.3 35294.1 31543.5 31885.7 31929.7 32000.5 32007.7 6149.5 6292.5 6307.2 8595.5 8784.1 9097.5 9145.6 9238.9 9304.3 9309.4 9383.5 9563.9 9567.3 9584.6 9594.3 9409.1 9474.4 9478.7 9492.1 9523.6 9003.4 9173.4 9193.4 9195.2 9198.6 33907.5 33968.3 34111.1 34138.0 34179.5 3416.2 3457.2 3473.3 3518.1 3530.4 24648.3 24730.9 24903.9 25003.7 25305.4 3323.7 3386.5 3420.6 3438.1 3448.2 161193.0 162563.0 162611.0 162708.0 162956.0 32469.5 33150.0 37513.2 42009.8 42251.3 42786.8 42926.0 43071.4 43133.0 43376.1 12358.8 12547.8 12550.4 12560.9 12641.7 9667.8 9723.1 9727.3 9729.9 9785.8 13256.3 13291.9 13394.4 13463.5 13668.5 5411.1 5497.9 5552.9 5580.9 5590.5 851.4 887.3 984.5 1005.5 1060.3 20312.8 21677.3 22169.2 22231.9 22380.6 1090.0 1096.2 1098.4 1106.2 1118.9 10727.2 11329.9 11551.2 13549.3 14453.9 237230.0 237918.0 238140.0 238629.0 238935.0 45991.5 46452.5 46553.3 46694.5 46850.8 1189.9 1237.1 1241.2 1241.5 1329.0 24219.5 24459.4 25555.4 25783.4 25812.2 31699.7 31807.7 31908.2 31953.7 32064.2 224540.0 226244.0 226456.0 227451.0 227764.0 27217.5 27875.3 27949.3 27979.0 28585.7 31860.4 32226.4 32908.3 32956.7 33006.5 29833.9 30147.4 30255.4 30290.0 31284.0 29719.3 29906.9 30236.8 30341.8 30558.1 29837.7 30255.1 30271.8 30512.7 30686.5 22757.5 26233.9 26343.0 26461.6 27384.0 21898.7 21909.0 22121.8 22243.8 22252.8 09:11:16 INFO - PID 4268 | 09:11:16 INFO - PID 4268 | [#2] jquery.html Cycles:180 Average:29540.30 Median:30124.50 stddev:76041.83 (252.4%) stddev-sans-first:76223.65 09:11:16 INFO - PID 4268 | Values: 390.1 390.7 395.7 403.2 411.3 13232.8 17919.9 18049.0 18739.2 18780.4 16807.7 16842.8 17027.2 18395.1 19052.6 5005.7 5672.6 5761.3 5776.8 5884.9 5607.7 5746.6 5758.1 5759.7 5769.0 5749.5 5774.9 6709.2 7031.1 7072.7 6668.4 7017.9 7035.9 7044.7 7061.9 6872.9 6924.0 6924.3 6932.6 6932.7 24406.1 24966.3 25143.9 25455.4 25760.1 2334.8 2391.3 2424.6 2431.9 2467.7 14319.9 15008.8 15387.5 15411.6 15681.5 2052.3 2057.7 2071.9 2093.1 2111.3 87408.0 91188.0 91379.0 93626.5 93747.0 18316.6 18365.5 18459.4 18484.6 18596.4 26341.0 33908.0 34304.0 34507.0 34753.3 8210.3 8390.7 8479.9 8660.4 8673.7 6713.9 6769.3 6914.5 6917.0 6920.8 9051.3 9114.0 9288.4 9369.0 9427.1 3539.8 3722.5 3780.7 3878.3 3902.2 820.5 834.7 840.2 847.0 873.7 19294.6 20389.3 20583.0 20676.9 20778.8 831.4 842.1 848.2 858.5 870.8 10512.2 11172.7 11206.9 11219.2 11264.6 454460.0 455947.0 459174.0 464948.0 465823.0 82803.0 91950.5 93088.0 94788.0 95167.0 1098.4 1112.6 1155.6 1167.5 1169.0 17478.4 17775.9 18517.4 18602.7 18740.9 41132.0 42343.7 42713.0 42950.4 43385.1 54398.0 54669.7 55142.4 55621.7 56686.7 10495.0 10506.9 10517.4 10599.1 10991.5 12334.8 12825.7 12873.0 13115.1 13181.8 10256.6 10458.0 10876.3 11373.4 11543.8 10727.8 10809.5 10816.6 11210.6 11335.2 11202.7 11332.3 11344.1 11590.3 11637.2 10258.7 10836.7 10865.2 10972.5 11185.9 10795.8 10954.4 11016.2 11196.3 11222.3 09:11:16 INFO - PID 4268 | 09:11:16 INFO - PID 4268 | [#3] mootools.html Cycles:180 Average:5681.40 Median:4543.40 stddev:3666.62 (80.7%) stddev-sans-first:3662.80 09:11:16 INFO - PID 4268 | Values: 1401.0 1415.4 1438.2 1447.1 1512.8 7746.2 7963.8 8998.8 9399.0 9406.3 8927.0 9295.2 9365.3 9386.6 9388.1 1979.4 2011.9 2020.0 2021.1 2023.1 1947.5 1988.2 1989.2 2002.0 2046.6 1962.3 1964.7 1966.2 1971.0 1989.2 1887.7 1896.7 1904.2 1908.6 1910.5 1895.9 1897.3 1913.2 1916.0 1964.3 9120.5 9176.0 9204.1 9212.6 9213.5 4488.4 4688.5 4703.0 4703.1 4705.2 2555.0 2706.9 2764.2 2772.9 2782.1 1006.2 1031.4 1032.3 1093.5 1101.0 13473.1 13539.3 13552.6 13571.4 13628.8 3743.6 3851.5 3992.7 4059.7 4066.7 11036.1 11081.7 11144.1 11166.2 11193.4 4641.2 4645.8 4650.7 4652.6 4653.9 3650.2 3656.2 4204.1 4302.4 4312.5 3351.5 3403.5 3422.9 3491.8 3503.1 934.0 949.7 959.9 965.0 970.2 727.8 728.2 745.2 748.5 762.9 4946.1 5000.9 5092.2 5142.8 5196.2 846.9 861.8 869.1 869.9 870.9 3153.2 3196.7 3229.6 3234.3 3322.9 11122.3 11468.1 11690.4 11700.9 11731.9 11338.6 11451.8 11562.4 11591.8 11596.1 8009.7 8012.3 8101.7 8108.4 8109.2 5122.4 5193.5 5209.9 5259.7 5281.7 10219.4 10241.3 10245.7 10385.6 10494.6 10377.4 10561.6 10585.8 10589.8 10622.6 7838.3 7846.4 7905.9 7970.9 8003.2 8791.0 8877.5 8888.3 8897.9 8931.0 7804.7 7932.8 7939.1 8076.3 8161.6 7803.1 7814.3 7851.4 7875.7 7977.3 6782.7 6805.5 6826.5 6892.9 6924.6 4654.7 4718.3 4756.5 4781.3 4870.1 4598.4 4631.0 4651.6 4731.2 4756.4 09:11:16 INFO - PID 4268 | 09:11:16 INFO - PID 4268 | [#4] prototype.html Cycles:180 Average:4868.83 Median:5264.82 stddev:1246.61 (23.7%) stddev-sans-first:1203.34 09:11:16 INFO - PID 4268 | Values: 362.4 367.2 369.2 370.7 381.9 5284.9 5295.4 5307.7 5389.3 5505.9 5223.3 5276.1 5290.9 5302.5 5325.9 4378.0 4417.7 4553.5 4564.4 4579.9 4544.9 4545.4 4559.3 4562.6 4568.9 4570.6 4583.5 4589.5 4591.2 4598.3 4460.8 4536.6 4546.1 4575.3 4597.4 4364.1 4373.4 4381.1 4386.4 4403.7 5208.2 5223.0 5239.8 5239.9 5253.6 5239.8 5378.7 5380.2 5407.8 5414.8 5699.0 5709.7 5711.4 5713.0 5715.1 4872.2 4882.1 4899.8 4907.7 4922.5 5742.5 5768.4 5884.5 6004.2 6047.2 4759.9 4816.0 4817.0 4837.6 4840.2 4896.4 4942.4 4945.1 4946.9 4958.9 5110.7 5200.6 5226.9 5228.3 5252.6 5625.3 5640.8 5647.5 5657.9 5665.5 1797.3 1820.3 1821.3 1824.4 1831.6 1421.8 1479.9 1495.8 1501.7 1525.6 4868.2 4876.7 4895.6 4913.2 4925.5 5748.2 5783.5 5827.0 5831.8 5833.8 4705.8 4905.3 4930.2 4940.1 4950.4 3057.4 3067.1 3075.8 3078.7 3080.9 5247.5 5396.1 5400.7 5416.6 5429.1 5902.0 5906.4 5948.4 5958.5 5978.7 5417.9 5422.3 5460.0 5461.9 5473.4 5656.3 5742.5 5771.3 5775.6 5797.8 5724.6 5724.7 5735.3 5746.5 5787.8 5205.5 5240.8 5342.6 5384.7 5459.1 5012.8 5038.5 5053.9 5059.8 5064.8 5601.8 5751.6 5756.4 5783.5 5799.1 5558.4 5591.6 5594.4 5607.7 5618.4 5565.4 5579.2 5590.8 5592.8 5603.3 5310.6 5456.3 5484.0 5495.8 5519.6 5601.8 5622.3 5629.2 5641.3 5648.5 5249.4 5421.9 5452.5 5466.7 5467.5 09:11:16 INFO - PID 4268 | 09:11:16 INFO - PID 4268 | [#5] yui.html Cycles:180 Average:15005.54 Median:23849.36 stddev:18094.96 (75.9%) stddev-sans-first:18113.59 09:11:16 INFO - PID 4268 | Values: 645.1 648.3 703.7 751.5 789.6 12961.9 12984.5 13144.5 13706.4 13862.0 12766.3 12909.0 12982.2 13026.9 13727.7 801.7 817.7 828.5 838.6 838.9 817.7 820.2 825.5 826.9 838.5 864.9 865.4 867.7 868.1 869.1 860.7 860.7 864.2 867.0 867.3 628.8 629.3 633.4 633.7 635.3 13439.7 13584.5 13620.3 13709.2 15097.9 12331.9 12333.1 13082.5 13544.0 13564.6 9857.7 9867.8 10338.2 10622.8 10738.6 3256.4 3277.4 3367.7 3423.8 3433.0 65785.7 67235.0 67559.7 68419.7 69901.3 10859.8 10968.9 11077.6 11152.5 11322.5 21182.0 21258.4 21376.0 21627.1 21696.8 7012.5 7090.2 7135.1 7216.9 7367.8 3523.2 3577.2 3621.8 3626.2 3632.8 2795.8 2822.5 2840.6 2848.0 2854.3 466.4 472.4 478.6 484.5 486.9 582.5 615.6 628.1 637.1 638.1 8895.4 9265.8 9466.6 9521.5 9529.6 598.1 620.5 621.8 625.1 629.3 4115.6 4310.3 4393.9 4416.6 4481.9 50009.5 55514.2 61743.0 65601.0 65692.3 54667.7 55089.7 55166.4 55498.4 55649.7 50612.0 51552.0 51587.7 52034.2 52344.5 7988.2 8409.0 8443.9 8490.9 8552.6 34812.7 34882.0 35530.6 35696.1 35716.8 40914.8 42546.4 43304.8 46754.3 47715.5 11726.8 12288.4 12606.8 12837.3 13148.3 13076.4 13875.9 13991.7 14257.9 14588.2 12066.1 12790.3 12885.9 13086.7 13442.7 12000.5 12381.6 12572.8 12588.2 13084.9 11483.7 12084.2 12158.5 12237.9 12563.9 10052.2 10149.9 10480.8 10496.3 11182.7 9362.8 9683.5 10825.4 10834.8 11042.2 09:11:16 INFO - PID 4268 | -------- Summary: end -------- 09:11:16 INFO - PID 4268 | 09:11:17 INFO - TEST-INFO | 4268: exit 0 09:11:17 INFO - TEST-OK | dromaeo_css | took 1121300ms 09:11:17 INFO - TEST-START | kraken 09:11:17 INFO - operating with platform_type : linux_ 09:11:17 INFO - Initialising browser for kraken test... 09:11:17 INFO - TEST-INFO | started process 4645 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpREWcNr/profile http://localhost:55012/getInfo.html) 09:11:22 INFO - PID 4645 | __metrics Screen width/height:1600/1200 09:11:22 INFO - PID 4645 | colorDepth:24 09:11:22 INFO - PID 4645 | Browser inner width/height: 1024/697 09:11:22 INFO - PID 4645 | __metrics 09:11:22 ERROR - PID 4645 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3350: TypeError: this.tabs is undefined 09:11:23 INFO - TEST-INFO | 4645: exit 0 09:11:23 INFO - Browser initialized. 09:11:23 INFO - Running cycle 1/1 for kraken test... 09:11:23 INFO - TEST-INFO | started process 4778 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpREWcNr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:11:36 INFO - PID 4778 | RSS: Main: 158609408 09:11:36 INFO - PID 4778 | 09:11:38 INFO - PID 4778 | Cycle 1(1): loaded http://localhost:55012/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55012/tests/kraken/driver.html?testName=audio-beat-detection) 09:11:39 INFO - PID 4778 | RSS: Main: 161996800 09:11:39 INFO - PID 4778 | 09:11:42 INFO - PID 4778 | Cycle 1(1): loaded http://localhost:55012/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55012/tests/kraken/driver.html?testName=audio-dft) 09:11:43 INFO - PID 4778 | RSS: Main: 161488896 09:11:43 INFO - PID 4778 | 09:11:46 INFO - PID 4778 | Cycle 1(1): loaded http://localhost:55012/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55012/tests/kraken/driver.html?testName=audio-fft) 09:11:46 INFO - PID 4778 | RSS: Main: 157036544 09:11:46 INFO - PID 4778 | 09:11:48 INFO - PID 4778 | Cycle 1(1): loaded http://localhost:55012/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55012/tests/kraken/driver.html?testName=audio-oscillator) 09:11:49 INFO - PID 4778 | RSS: Main: 158543872 09:11:49 INFO - PID 4778 | 09:11:51 INFO - PID 4778 | Cycle 1(1): loaded http://localhost:55012/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55012/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:11:52 INFO - PID 4778 | RSS: Main: 158507008 09:11:52 INFO - PID 4778 | 09:11:55 INFO - PID 4778 | Cycle 1(1): loaded http://localhost:55012/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55012/tests/kraken/driver.html?testName=imaging-darkroom) 09:11:56 INFO - PID 4778 | RSS: Main: 163700736 09:11:56 INFO - PID 4778 | 09:11:59 INFO - PID 4778 | Cycle 1(1): loaded http://localhost:55012/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55012/tests/kraken/driver.html?testName=imaging-desaturate) 09:12:00 INFO - PID 4778 | RSS: Main: 161730560 09:12:00 INFO - PID 4778 | 09:12:03 INFO - PID 4778 | Cycle 1(1): loaded http://localhost:55012/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55012/tests/kraken/driver.html?testName=json-parse-financial) 09:12:03 INFO - PID 4778 | RSS: Main: 162078720 09:12:03 INFO - PID 4778 | 09:12:05 INFO - PID 4778 | Cycle 1(1): loaded http://localhost:55012/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55012/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:12:06 INFO - PID 4778 | RSS: Main: 162467840 09:12:06 INFO - PID 4778 | 09:12:07 INFO - PID 4778 | Cycle 1(1): loaded http://localhost:55012/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55012/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:12:08 INFO - PID 4778 | RSS: Main: 162643968 09:12:08 INFO - PID 4778 | 09:12:10 INFO - PID 4778 | Cycle 1(1): loaded http://localhost:55012/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55012/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:12:11 INFO - PID 4778 | RSS: Main: 162967552 09:12:11 INFO - PID 4778 | 09:12:14 INFO - PID 4778 | Cycle 1(1): loaded http://localhost:55012/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55012/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:12:15 INFO - PID 4778 | RSS: Main: 163311616 09:12:15 INFO - PID 4778 | 09:12:18 INFO - PID 4778 | Cycle 1(1): loaded http://localhost:55012/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55012/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:12:18 INFO - PID 4778 | RSS: Main: 166322176 09:12:18 INFO - PID 4778 | 09:12:20 INFO - PID 4778 | Cycle 1(1): loaded http://localhost:55012/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55012/tests/kraken/driver.html?testName=ai-astar) 09:12:20 INFO - PID 4778 | RSS: Main: 167600128 09:12:20 INFO - PID 4778 | 09:12:20 INFO - PID 4778 | __start_tp_report 09:12:20 INFO - PID 4778 | _x_x_mozilla_page_load 09:12:20 INFO - PID 4778 | _x_x_mozilla_page_load_details 09:12:20 INFO - PID 4778 | |i|pagename|runs| 09:12:20 INFO - PID 4778 | |0;ai-astar;111;119;121;119;118;117;119;118;119;119 09:12:20 INFO - PID 4778 | |1;audio-beat-detection;152;128;125;166;126;126;127;126;146;128 09:12:20 INFO - PID 4778 | |2;audio-dft;168;164;165;171;167;172;164;163;163;166 09:12:20 INFO - PID 4778 | |3;audio-fft;74;76;76;109;76;79;75;79;92;76 09:12:20 INFO - PID 4778 | |4;audio-oscillator;84;85;109;86;85;84;103;85;85;101 09:12:20 INFO - PID 4778 | |5;imaging-gaussian-blur;115;115;113;114;113;116;115;114;113;114 09:12:20 INFO - PID 4778 | |6;imaging-darkroom;152;151;150;150;150;151;150;149;153;151 09:12:20 INFO - PID 4778 | |7;imaging-desaturate;95;94;94;94;95;95;94;95;95;94 09:12:20 INFO - PID 4778 | |8;json-parse-financial;71;70;72;71;75;72;72;71;71;72 09:12:20 INFO - PID 4778 | |9;json-stringify-tinderbox;55;55;58;58;56;56;56;56;55;55 09:12:20 INFO - PID 4778 | |10;stanford-crypto-aes;98;81;92;81;82;81;82;82;82;83 09:12:20 INFO - PID 4778 | |11;stanford-crypto-ccm;135;134;134;132;136;140;134;144;179;145 09:12:20 INFO - PID 4778 | |12;stanford-crypto-pbkdf2;188;193;196;196;199;201;197;191;192;198 09:12:20 INFO - PID 4778 | |13;stanford-crypto-sha256-iterative;73;73;73;71;70;70;71;71;75;70 09:12:20 INFO - PID 4778 | __end_tp_report 09:12:20 INFO - PID 4778 | __start_cc_report 09:12:20 INFO - PID 4778 | _x_x_mozilla_cycle_collect,876 09:12:21 INFO - PID 4778 | __end_cc_report 09:12:21 INFO - PID 4778 | __startTimestamp1489853540993__endTimestamp 09:12:21 INFO - PID 4778 | 09:12:21 INFO - PID 4778 | ------- Summary: start ------- 09:12:21 INFO - PID 4778 | Number of tests: 14 09:12:21 INFO - PID 4778 | 09:12:21 INFO - PID 4778 | [#0] ai-astar Cycles:10 Average:118.00 Median:119.00 stddev:2.67 (2.2%) stddev-sans-first:1.09 09:12:21 INFO - PID 4778 | Values: 111.0 119.0 121.0 119.0 118.0 117.0 119.0 118.0 119.0 119.0 09:12:21 INFO - PID 4778 | 09:12:21 INFO - PID 4778 | [#1] audio-beat-detection Cycles:10 Average:135.00 Median:128.00 stddev:14.44 (11.3%) stddev-sans-first:13.94 09:12:21 INFO - PID 4778 | Values: 152.0 128.0 125.0 166.0 126.0 126.0 127.0 126.0 146.0 128.0 09:12:21 INFO - PID 4778 | 09:12:21 INFO - PID 4778 | [#2] audio-dft Cycles:10 Average:166.30 Median:166.50 stddev:3.20 (1.9%) stddev-sans-first:3.33 09:12:21 INFO - PID 4778 | Values: 168.0 164.0 165.0 171.0 167.0 172.0 164.0 163.0 163.0 166.0 09:12:21 INFO - PID 4778 | 09:12:21 INFO - PID 4778 | [#3] audio-fft Cycles:10 Average:81.20 Median:76.00 stddev:11.04 (14.5%) stddev-sans-first:11.40 09:12:21 INFO - PID 4778 | Values: 74.0 76.0 76.0 109.0 76.0 79.0 75.0 79.0 92.0 76.0 09:12:21 INFO - PID 4778 | 09:12:21 INFO - PID 4778 | [#4] audio-oscillator Cycles:10 Average:90.70 Median:85.00 stddev:9.63 (11.3%) stddev-sans-first:9.90 09:12:21 INFO - PID 4778 | Values: 84.0 85.0 109.0 86.0 85.0 84.0 103.0 85.0 85.0 101.0 09:12:21 INFO - PID 4778 | 09:12:21 INFO - PID 4778 | [#5] imaging-gaussian-blur Cycles:10 Average:114.20 Median:114.50 stddev:1.03 (0.9%) stddev-sans-first:1.05 09:12:21 INFO - PID 4778 | Values: 115.0 115.0 113.0 114.0 113.0 116.0 115.0 114.0 113.0 114.0 09:12:21 INFO - PID 4778 | 09:12:21 INFO - PID 4778 | [#6] imaging-darkroom Cycles:10 Average:150.70 Median:151.00 stddev:1.16 (0.8%) stddev-sans-first:1.13 09:12:21 INFO - PID 4778 | Values: 152.0 151.0 150.0 150.0 150.0 151.0 150.0 149.0 153.0 151.0 09:12:21 INFO - PID 4778 | 09:12:21 INFO - PID 4778 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:95.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 09:12:21 INFO - PID 4778 | Values: 95.0 94.0 94.0 94.0 95.0 95.0 94.0 95.0 95.0 94.0 09:12:21 INFO - PID 4778 | 09:12:21 INFO - PID 4778 | [#8] json-parse-financial Cycles:10 Average:71.70 Median:72.00 stddev:1.34 (1.9%) stddev-sans-first:1.39 09:12:21 INFO - PID 4778 | Values: 71.0 70.0 72.0 71.0 75.0 72.0 72.0 71.0 71.0 72.0 09:12:21 INFO - PID 4778 | 09:12:21 INFO - PID 4778 | [#9] json-stringify-tinderbox Cycles:10 Average:56.00 Median:56.00 stddev:1.15 (2.1%) stddev-sans-first:1.17 09:12:21 INFO - PID 4778 | Values: 55.0 55.0 58.0 58.0 56.0 56.0 56.0 56.0 55.0 55.0 09:12:21 INFO - PID 4778 | 09:12:21 INFO - PID 4778 | [#10] stanford-crypto-aes Cycles:10 Average:84.40 Median:82.00 stddev:5.80 (7.1%) stddev-sans-first:3.48 09:12:21 INFO - PID 4778 | Values: 98.0 81.0 92.0 81.0 82.0 81.0 82.0 82.0 82.0 83.0 09:12:21 INFO - PID 4778 | 09:12:21 INFO - PID 4778 | [#11] stanford-crypto-ccm Cycles:10 Average:141.30 Median:138.00 stddev:13.98 (10.1%) stddev-sans-first:14.64 09:12:21 INFO - PID 4778 | Values: 135.0 134.0 134.0 132.0 136.0 140.0 134.0 144.0 179.0 145.0 09:12:21 INFO - PID 4778 | 09:12:21 INFO - PID 4778 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:195.10 Median:196.50 stddev:4.01 (2.0%) stddev-sans-first:3.33 09:12:21 INFO - PID 4778 | Values: 188.0 193.0 196.0 196.0 199.0 201.0 197.0 191.0 192.0 198.0 09:12:21 INFO - PID 4778 | 09:12:21 INFO - PID 4778 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.70 Median:72.00 stddev:1.70 (2.4%) stddev-sans-first:1.74 09:12:21 INFO - PID 4778 | Values: 73.0 73.0 73.0 71.0 70.0 70.0 71.0 71.0 75.0 70.0 09:12:21 INFO - PID 4778 | -------- Summary: end -------- 09:12:21 INFO - PID 4778 | 09:12:22 INFO - TEST-INFO | 4778: exit 0 09:12:22 INFO - TEST-OK | kraken | took 65012ms 09:12:22 INFO - SUITE-END | took 1186s 09:12:22 INFO - Completed test suite (00:19:46) 09:12:22 INFO - javascript benchmark 09:12:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6817.300173810657, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2678.892844286229, 2689.757921787039, 2701.6082668013137, 2711.3763834318106, 2721.863906804658, 4984.87537811554, 5030.446650868402, 5057.595392368611, 5093.745312734358, 5103.055934652088, 5003.47463516331, 5076.79692812288, 5103.362079740024, 5140.7172605506685, 5158.509941555546, 4593.012502312003, 4631.698939568934, 4653.813847446098, 4679.368285281477, 4700.600448961843, 4674.976625116869, 4683.555062269079, 4702.130105930397, 4730.101280756645, 4753.928691069636, 4563.977180114111, 4596.333531637893, 4596.54034596541, 4637.095766325568, 4643.326717625959, 4626.398568186148, 4637.5594318540025, 4642.79107440166, 4663.067386522709, 4676.392069031004, 4557.0885822835235, 4588.908221835544, 4590.311453282001, 4609.516000819933, 4663.953360466386, 4893.510648935114, 4942.382202224722, 5005.374328208974, 5020.246962955549, 5027.547520723116, 5486.91769623456, 5491.395946445888, 5502.174673798922, 5518.668879867221, 5559.082751346023, 5527.39198688144, 5539.944600553983, 5540.63985840919, 5556.472135147195, 5572.665640061568, 4915.4347250065985, 4959.603231741452, 4960.950390496121, 5171.862190318124, 5214.270002199695, 5423.755930983115, 5429.9411614734745, 5457.208704737791, 5461.180822876521, 5482.698451585121, 5179.81870634526, 5417.322834645669, 5454.8909021819345, 5464.535514481234, 5479.561635069185, 5461.481159289861, 5491.36849262332, 5492.0, 5500.642458240201, 5509.669419834822, 5464.808731694451, 5469.945300546943, 5470.425605311385, 5489.451054894558, 5549.278593782863, 5307.65500242483, 5378.220158077057, 5428.538574221236, 5476.561875050066, 5494.066008778517, 4614.215583350839, 4624.1445332613575, 4624.5375462453485, 4655.4180727409075, 4674.0, 3973.8410463581713, 3974.0462289050997, 3995.360742281221, 3998.460207871958, 4025.275450418953, 4623.214053610895, 4686.297055441642, 4727.929081063787, 4731.95268047322, 4746.572808447257, 5487.122060470306, 5498.092814685532, 5498.835034948958, 5523.061079616475, 5523.171524271392, 4688.296755486705, 4718.952810471851, 4727.763611819395, 4733.266343716729, 4763.237881938873, 4823.5658790709, 4883.121038213085, 4884.0, 4916.311716359713, 4944.728039957765, 5193.246979187921, 5203.297554830049, 5225.973870130777, 5237.135872580981, 5254.9474505255985, 5380.031594312904, 5447.455254474521, 5548.167774833807, 5549.084401073777, 5559.11054231321, 5152.819651312186, 5154.742262886915, 5207.531322181022, 5207.6875387476875, 5209.765560549708, 5222.138347172674, 5332.973335133454, 5340.252364668871, 5375.381831089318, 5413.7834486620095, 5426.348838139448, 5437.211604317273, 5443.455654434525, 5444.482774136451, 5448.155535891943, 5218.921716174336, 5250.606204534752, 5255.185446255837, 5256.947430525645, 5261.421243663271, 5223.3209682741, 5235.685858848481, 5242.554382877498, 5254.054270231395, 5254.921176182437, 5175.094358487175, 5187.273781670645, 5199.038177937094, 5249.89500209986, 5258.50044245796, 5358.035553600389, 5374.570034397317, 5376.731163441734, 5412.891742165056, 5424.538914192338, 5172.275881376686, 5242.5281724644965, 5243.187305967581, 5251.606129540376, 5304.124819404909], "value": 4988.1678012132015, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [688.4182865478671, 705.4920457270766, 779.4543819326469, 790.332169316927, 795.8607243732349, 34066.31867362651, 35153.47269790955, 35175.296494070106, 35206.295874082534, 35294.117647058774, 31543.526847097255, 31885.68114318856, 31929.68070319302, 32000.519992200076, 32007.67992320076, 6149.508039356863, 6292.4966002719675, 6307.211598550181, 8595.527246001468, 8784.07767184446, 9097.454152750823, 9145.588448519798, 9238.86141707875, 9304.348695591312, 9309.394889332172, 9383.530823458834, 9563.856542151872, 9567.282453815957, 9584.616615335379, 9594.28042896782, 9409.059094090604, 9474.38416502925, 9478.668246611405, 9492.098250666175, 9523.619055237818, 9003.414778039472, 9173.403728757676, 9193.448393096436, 9195.21840643546, 9198.63205471787, 33907.491387629205, 33968.320633587435, 34111.14722131967, 34138.0, 34179.487307690404, 3416.197193659488, 3457.170279133015, 3473.3053389321985, 3518.0501264658656, 3530.435130379127, 24648.260552183463, 24730.876345618337, 24903.87548062266, 25003.749962500508, 25305.36736581582, 3323.6510166432417, 3386.475096360068, 3420.6066302375298, 3438.1060924159647, 3448.1724386147143, 161193.0, 162562.9999999988, 162610.9999999988, 162708.0, 162956.0, 32469.51295730566, 33150.0, 37513.24973500515, 42009.78995105005, 42251.33847561304, 42786.78606606947, 42926.0, 43071.35392969108, 43133.0, 43376.13247735028, 12358.814617780741, 12547.811782823264, 12550.372481375894, 12560.937195313965, 12641.683957900943, 9667.758306042406, 9723.076307750895, 9727.319087663796, 9729.854052189223, 9785.755356116011, 13256.337183140804, 13291.867081329256, 13394.397252123677, 13463.461461541428, 13668.52160174389, 5411.134218525017, 5497.945020549823, 5552.944470555323, 5580.888382232414, 5590.546457920888, 851.3529717414809, 887.3389324952951, 984.5175863826673, 1005.4620777883944, 1060.3213943076437, 20312.796872031387, 21677.34967950964, 22169.22407715722, 22231.886666066574, 22380.55238895213, 1089.9945500272606, 1096.1888202729922, 1098.4288170151476, 1106.247751528952, 1118.8769235384102, 10727.249092563601, 11329.886701133048, 11551.249168804, 13549.25479098659, 14453.85546144546, 237230.0, 237918.0, 238140.0, 238629.0, 238935.0, 45991.540084598724, 46452.5354746455, 46553.30170047452, 46694.53305466902, 46850.76574617105, 1189.8929096381366, 1237.127824883455, 1241.1684171605045, 1241.453760345445, 1328.9601311960657, 24219.496654986113, 24459.388515286548, 25555.36111597225, 25783.355416114748, 25812.22563323103, 31699.683003169674, 31807.681923180473, 31908.202294941882, 31953.68046319507, 32064.198395040312, 224540.0, 226244.0, 226456.0, 227451.0, 227764.0, 27217.455650887267, 27875.303117422227, 27949.30126746766, 27979.0, 28585.71414285914, 31860.362792743552, 32226.35547289088, 32908.341833162725, 32956.67043329536, 33006.504902426, 29833.850830746575, 30147.397052058397, 30255.394892101594, 30290.0, 31284.0, 29719.25701857471, 29906.85046574753, 30236.84881575578, 30341.848290758404, 30558.08325750231, 29837.70162298349, 30255.09234722962, 30271.848640756656, 30512.694873051874, 30686.539701904934, 22757.544849102593, 26233.868830655723, 26343.0, 26461.60307595349, 27384.0, 21898.67151992753, 21909.0, 22121.778782212616, 22243.77756222417, 22252.777472225716], "value": 15700.969967194082, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [390.14558117722186, 390.6874500399683, 395.6656625151746, 403.2197697455423, 411.34801339876293, 13232.801507977363, 17919.91040044801, 18049.0, 18739.156737946792, 18780.436586902397, 16807.66384672306, 16842.83157168431, 17027.233774480148, 18395.148751943438, 19052.61894762104, 5005.674631148972, 5672.602917795756, 5761.30864296285, 5776.826695199151, 5884.941150588482, 5607.719614019303, 5746.626469279484, 5758.107493338539, 5759.740811663485, 5769.048107062351, 5749.482546570808, 5774.913376299359, 6709.228438729536, 7031.1211098612675, 7072.717091316367, 6668.399844014062, 7017.8596428071405, 7035.9296407036045, 7044.718211271543, 7061.929380706179, 6872.896906546406, 6924.0, 6924.307569243086, 6932.618705971169, 6932.72269109235, 24406.145784897442, 24966.251012469656, 25143.87428062866, 25455.363615909566, 25760.09839655603, 2334.8365614407, 2391.330427480308, 2424.5756992526412, 2431.9148829790875, 2467.716212635552, 14319.928400358036, 15008.774868376982, 15387.538373848802, 15411.614709632237, 15681.451149209723, 2052.332991777678, 2057.7324947756847, 2071.9378418647466, 2093.110428068065, 2111.3349294972036, 87408.0, 91188.0, 91379.0, 93626.5318673409, 93747.0, 18316.63366732658, 18365.54086147857, 18459.446216613524, 18484.630307393778, 18596.44210673682, 26341.0, 33908.0, 34304.0, 34507.0, 34753.30493390118, 8210.343172546181, 8390.664373424994, 8479.915200847912, 8660.39377243587, 8673.696420625249, 6713.865722685519, 6769.289224631441, 6914.51598388108, 6917.0, 6920.757773477904, 9051.321150913707, 9113.954430227806, 9288.442693438416, 9368.95315523418, 9427.104425079606, 3539.805310707936, 3722.4974628425357, 3780.7353485256317, 3878.301905657009, 3902.180542086137, 820.5076953827655, 834.7328854766416, 840.1976112812249, 846.9915300847036, 873.6549063120032, 19294.614107718047, 20389.286374976804, 20583.0, 20676.896615517126, 20778.792212077988, 831.4013909984922, 842.0653075086649, 848.2154007543121, 858.4934888415739, 870.7735988642999, 10512.21158413122, 11172.720681983015, 11206.887931120584, 11219.1585631078, 11264.605738799266, 454460.0, 455947.0, 459174.0, 464948.0, 465823.0, 82803.0, 91950.54024729834, 93088.0, 94788.0, 95167.0, 1098.4068602954476, 1112.6495154026456, 1155.5551112821456, 1167.497975870384, 1168.9941550292194, 17478.388256411472, 17775.911120444314, 18517.44447666572, 18602.720959185353, 18740.90629546898, 41132.0, 42343.74766387808, 42713.0, 42950.35574466448, 43385.13229735451, 54398.0, 54669.72665136649, 55142.44857551373, 55621.72189139028, 56686.7165664169, 10495.0, 10506.947465262625, 10517.368957862553, 10599.099076578577, 10991.505382257657, 12334.81497777552, 12825.679358015752, 12873.03452241067, 13115.147515411849, 13181.80227296572, 10256.58973641046, 10458.0, 10876.347419154876, 11373.431328433711, 11543.8268425972, 10727.839082413613, 10809.459527023775, 10816.56733730642, 11210.607628732956, 11335.206535542433, 11202.66392008241, 11332.263402878787, 11344.092472602008, 11590.304581725124, 11637.243579167327, 10258.743531411774, 10836.72428789963, 10865.18511111654, 10972.506237219504, 11185.944070279596, 10795.838062429228, 10954.397508137128, 11016.17378696585, 11196.272242304223, 11222.250548615315], "value": 10186.528026559341, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1400.9803921568628, 1415.384307826095, 1438.1946110178294, 1447.0738727214577, 1512.8411516790736, 7746.225377462251, 7963.840723185533, 8998.820023599525, 9398.953005234982, 9406.341556091067, 8927.0, 9295.163435290822, 9365.34442589019, 9386.624535018607, 9388.061193880609, 1979.3962841333423, 2011.9094640741127, 2020.0303854150018, 2021.1006102284489, 2023.1199428248758, 1947.4936516505668, 1988.204718112752, 1989.2440872468442, 2001.979980200194, 2046.6213750456138, 1962.2543433495323, 1964.6856502959529, 1966.2331690640663, 1970.980290197101, 1989.2440872468442, 1887.6979683250752, 1896.7344571759966, 1904.228787341129, 1908.5610309628862, 1910.4555201767498, 1895.9431217063511, 1897.3359324236408, 1913.23470611755, 1916.0, 1964.3205059673917, 9120.498372589505, 9176.0, 9204.125608067223, 9212.585433655502, 9213.493257870814, 4488.3940668010055, 4688.531146885284, 4703.0, 4703.106409782131, 4705.247160454311, 2554.961675574868, 2706.8917243310243, 2764.2398340456334, 2772.9306767330977, 2782.095818858859, 1006.1699098243905, 1031.381171297223, 1032.2774058159318, 1093.5352475198008, 1101.0475938313382, 13473.124246924017, 13539.255340956244, 13552.59342219735, 13571.439284482312, 13628.795568066585, 3743.550773907094, 3851.5185601799776, 3992.680585553207, 4059.6955228357992, 4066.6543343816106, 11036.061934735477, 11081.667549973352, 11144.108471322275, 11166.218364714392, 11193.440327983568, 4641.2109941310055, 4645.767711614406, 4650.651201159926, 4652.581267685924, 4653.86038418848, 3650.1969566695284, 3656.1956369598647, 4204.096119334357, 4302.397664326997, 4312.482502099768, 3351.4972754086593, 3403.5235067090625, 3422.931541369159, 3491.825408729553, 3503.054175372635, 933.9579718912665, 949.6960972488876, 959.9424034557949, 965.0012237334325, 970.1656575345197, 727.7998550398682, 728.2098922668963, 745.1691364128957, 748.4648476339444, 762.8893810397461, 4946.08497427977, 5000.899982000413, 5092.185250359925, 5142.845714628567, 5196.220566914993, 846.8687353460224, 861.810401711635, 869.1352104655781, 869.9173578510031, 870.8867847179826, 3153.195935036551, 3196.7282780963415, 3229.6285927118684, 3234.2884565395575, 3322.900312990614, 11122.277051991594, 11468.13988950832, 11690.415479226005, 11700.941495292469, 11731.88268117325, 11338.60314888975, 11451.828222576669, 11562.364069976233, 11591.76816463666, 11596.130290228266, 8009.679612815422, 8012.278894899487, 8101.675932962616, 8108.391870609727, 8109.189081091844, 5122.385313762373, 5193.480651934777, 5209.947900520946, 5259.710715910662, 5281.735913204401, 10219.386836789816, 10241.334313269614, 10245.692629221136, 10385.584576616851, 10494.632687855888, 10377.377357358582, 10561.63034293796, 10585.841212381974, 10589.841152382874, 10622.628208012682, 7838.255365740246, 7846.411519136202, 7905.881411778713, 7970.880436793337, 8003.19968003195, 8791.0, 8877.467351958903, 8888.33337499703, 8897.911020889967, 8930.955345223232, 7804.6878124874365, 7932.801679958047, 7939.0870049945, 8076.313513351431, 8161.632726527202, 7803.141654418123, 7814.257645523666, 7851.411144164096, 7875.684972601031, 7977.321927636216, 6782.728690852508, 6805.4895882808, 6826.488013398915, 6892.896606550806, 6924.619145947021, 4654.697444666086, 4718.31584420264, 4756.500567440507, 4781.306710527024, 4870.05034018363, 4598.402207712976, 4631.0, 4651.627869770478, 4731.172044892061, 4756.381670382828], "value": 4301.320643617928, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [362.3513910100919, 367.23798118903284, 369.1841031320781, 370.65343903450275, 381.8854343696892, 5284.920726189105, 5295.391030031547, 5307.70807605582, 5389.326334208224, 5505.944940550593, 5223.320968274119, 5276.076686579852, 5290.894182116365, 5302.549283310914, 5325.946740532593, 4377.956220437794, 4417.690761646686, 4553.453585569736, 4564.360989461478, 4579.908401831962, 4544.9091018179615, 4545.409096817409, 4559.316102584606, 4562.566556177175, 4568.926302318969, 4570.611498022672, 4583.541645835407, 4589.472210695762, 4591.242444996572, 4598.333241679954, 4460.79926403311, 4536.614387777043, 4546.090781843644, 4575.290829921368, 4597.42532183477, 4364.061726728737, 4373.387725718402, 4381.07997320563, 4386.429764130675, 4403.691741578091, 5208.192730126836, 5223.0, 5239.79040838366, 5239.869003274948, 5253.553447956928, 5239.816606418757, 5378.704171270579, 5380.24676545284, 5407.837764867021, 5414.810481633163, 5699.0, 5709.685967271798, 5711.400302968212, 5713.0, 5715.056880660595, 4872.171730805771, 4882.145624515695, 4899.779509922062, 4907.656464047518, 4922.458529561745, 5742.454466825645, 5768.3654797972185, 5884.499817515559, 6004.18943442634, 6047.15339852421, 4759.904801903943, 4815.951840481621, 4816.95183048172, 4837.637177211724, 4840.177169881129, 4896.387951506062, 4942.406911170682, 4945.1346014448045, 4946.901061978812, 4958.851234462972, 5110.71891045996, 5200.557952573998, 5226.947730522722, 5228.268417082546, 5252.632315737938, 5625.268715067097, 5640.830775076754, 5647.548196144298, 5657.915131273035, 5665.518430933417, 1797.2631221199244, 1820.3173809821317, 1821.2623887325653, 1824.3888297420247, 1831.624516974017, 1421.7914772443341, 1479.9334029968677, 1495.7606782914681, 1501.6996600679909, 1525.59571713496, 4868.172410690191, 4876.731779752076, 4895.583875370633, 4913.1893237616105, 4925.458199598042, 5748.223989761413, 5783.479486846204, 5826.970865145647, 5831.795887144014, 5833.79581714638, 4705.8352957647, 4905.264210368473, 4930.211166213388, 4940.061388336277, 4950.43070046942, 3057.4343746406985, 3067.1412004638737, 3075.815451072943, 3078.6921307869034, 3080.92297692555, 5247.475252474798, 5396.109641909041, 5400.729963501809, 5416.647917885325, 5429.104197807317, 5901.970490147608, 5906.40935906406, 5948.434898684618, 5958.480587450173, 5978.671173085435, 5417.945820541823, 5422.322209723785, 5460.0, 5461.890762184655, 5473.357992602283, 5656.321241451053, 5742.483176514133, 5771.286426437712, 5775.595708300379, 5797.796837202005, 5724.599278050497, 5724.685142317213, 5735.340435849931, 5746.482816546531, 5787.826365209051, 5205.479452054764, 5240.768419421509, 5342.6260161788305, 5384.730763461889, 5459.08931873841, 5012.8496145115705, 5038.470960549236, 5053.898922021613, 5059.772310245974, 5064.848054558369, 5601.831945041656, 5751.626144300607, 5756.366799652119, 5783.508401785895, 5799.130130480293, 5558.388577256418, 5591.608587398843, 5594.440555944373, 5607.663540187602, 5618.438156184349, 5565.415631358649, 5579.16312553104, 5590.832275031756, 5592.832215033555, 5603.299587551556, 5310.628256022196, 5456.263404440349, 5484.0, 5495.835124946258, 5519.586031047607, 5601.823617040304, 5622.297212848394, 5629.211910332475, 5641.26663533738, 5648.519875810603, 5249.396319423316, 5421.864453388539, 5452.5365343946205, 5466.671999680032, 5467.45325467466], "value": 4503.237389883999, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [645.1226332188226, 648.3419329380677, 703.6692754405431, 751.5040073551456, 789.6486063701653, 12961.935190324048, 12984.480620775157, 13144.47422103117, 13706.383212755412, 13862.0, 12766.297853618047, 12909.031822613295, 12982.156159849603, 13026.869731302684, 13727.725445491109, 801.6512816924657, 817.6770175780578, 828.5235989306143, 838.6393850644221, 838.8741688746676, 817.6811043692935, 820.1757233979841, 825.47582285249, 826.8759686047081, 838.5262326785347, 864.8962124545063, 865.3553102938288, 867.6659486097834, 868.1231955724701, 869.0787764969153, 860.6557377049168, 860.746079906426, 864.1703964194318, 866.9653213871437, 867.332154241237, 628.767356078254, 629.3046183966742, 633.4077637409038, 633.7465013994394, 635.2757856044113, 13439.66400839977, 13584.456621735217, 13620.250886201255, 13709.17744935297, 15097.924510377377, 12331.876681233163, 12333.136680432373, 13082.542111026163, 13544.0, 13564.593062208149, 9857.704268871945, 9867.802643947081, 10338.22463315247, 10622.78754424915, 10738.570457181704, 3256.4138455078078, 3277.3772983133126, 3367.7305815534696, 3423.7945723256435, 3432.9656703433147, 65785.67107164481, 67235.0, 67559.66220168868, 68419.65790171067, 69901.30098698998, 10859.78280434387, 10968.945155274172, 11077.612283570037, 11152.498137583829, 11322.490487928064, 21182.0, 21258.362249132242, 21376.0, 21627.134914603554, 21696.78303216979, 7012.509124361246, 7090.184628767654, 7135.143782746104, 7216.927830721731, 7367.81580460482, 3523.1720545671747, 3577.159367548624, 3621.8370173342264, 3626.202235508184, 3632.8365223565, 2795.8182718123257, 2822.520171570838, 2840.573913912888, 2848.0, 2854.300696329371, 466.37272867992493, 472.4283616823613, 478.5740690785204, 484.48886424821444, 486.85394381685353, 582.4728620598365, 615.6398506873529, 628.13003989474, 637.0698779781478, 638.0779773227734, 8895.396451172603, 9265.814683706289, 9466.621335146654, 9521.476318802394, 9529.618815247313, 598.1207624791549, 620.454000479575, 621.8205190806285, 625.1497962770628, 629.2794750011211, 4115.609017143366, 4310.267254566731, 4393.934090988638, 4416.580424859633, 4481.932771008437, 50009.49990500121, 55514.16728749072, 61743.0, 65601.0, 65692.34307656958, 54667.72666136644, 55089.72455137699, 55166.448335516936, 55498.44501555013, 55649.721751390985, 50612.0, 51551.99999999925, 51587.742061290206, 52034.21948670773, 52344.47655523472, 7988.201179881965, 8408.957955210184, 8443.873341899754, 8490.904739109612, 8552.57237138153, 34812.65187348094, 34882.0, 35530.644693552735, 35696.10759731028, 35716.82141589275, 40914.795426022676, 42546.361804572334, 43304.78347608242, 46754.29868551906, 47715.522844771105, 11726.765464690829, 12288.385580721108, 12606.810897836356, 12837.293948832903, 13148.276844773629, 13076.411561479566, 13875.86124138786, 13991.650208744863, 14257.928710356382, 14588.197649128975, 12066.057975353062, 12790.296533690736, 12885.935570322088, 13086.672833179247, 13442.731145377233, 12000.459979300778, 12381.62855114348, 12572.811407828707, 12588.244705317711, 13084.869151308745, 11483.655490335303, 12084.214526055779, 12158.45286962071, 12237.938810305892, 12563.937180314406, 10052.195824333889, 10149.898501014895, 10480.842787358348, 10496.323437523688, 11182.66452006441, 9362.76593085151, 9683.467409292556, 10825.350478971286, 10834.837477438003, 11042.227044106836], "value": 6495.532068479321, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1570.8000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 119.0, 121.0, 119.0, 118.0, 117.0, 119.0, 118.0, 119.0, 119.0], "value": 118.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 128.0, 125.0, 166.0, 126.0, 126.0, 127.0, 126.0, 146.0, 128.0], "value": 135.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 164.0, 165.0, 171.0, 167.0, 172.0, 164.0, 163.0, 163.0, 166.0], "value": 166.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 76.0, 109.0, 76.0, 79.0, 75.0, 79.0, 92.0, 76.0], "value": 81.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 109.0, 86.0, 85.0, 84.0, 103.0, 85.0, 85.0, 101.0], "value": 90.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 115.0, 113.0, 114.0, 113.0, 116.0, 115.0, 114.0, 113.0, 114.0], "value": 114.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 151.0, 150.0, 150.0, 150.0, 151.0, 150.0, 149.0, 153.0, 151.0], "value": 150.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 94.0, 95.0, 95.0, 94.0, 95.0, 95.0, 94.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 70.0, 72.0, 71.0, 75.0, 72.0, 72.0, 71.0, 71.0, 72.0], "value": 71.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 58.0, 58.0, 56.0, 56.0, 56.0, 56.0, 55.0, 55.0], "value": 56.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 81.0, 92.0, 81.0, 82.0, 81.0, 82.0, 82.0, 82.0, 83.0], "value": 84.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 134.0, 134.0, 132.0, 136.0, 140.0, 134.0, 144.0, 179.0, 145.0], "value": 141.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 193.0, 196.0, 196.0, 199.0, 201.0, 197.0, 191.0, 192.0, 198.0], "value": 195.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 73.0, 71.0, 70.0, 70.0, 71.0, 71.0, 75.0, 70.0], "value": 71.7, "unit": "score"}]}]} 09:12:22 INFO - Return code: 0 09:12:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:12:22 ERROR - # TBPL SUCCESS # 09:12:22 INFO - Running post-action listener: _resource_record_post_action 09:12:22 INFO - [mozharness: 2017-03-18 16:12:22.805474Z] Finished run-tests step (success) 09:12:22 INFO - Running post-run listener: _resource_record_post_run 09:12:22 INFO - Total resource usage - Wall time: 1202s; CPU: 13.0%; Read bytes: 425984; Write bytes: 372191232; Read time: 132; Write time: 233068 09:12:22 INFO - TinderboxPrint: CPU usage
13.3% 09:12:22 INFO - TinderboxPrint: I/O read bytes / time
425,984 / 132 09:12:22 INFO - TinderboxPrint: I/O write bytes / time
372,191,232 / 233,068 09:12:22 INFO - TinderboxPrint: CPU idle
8,311.9 (86.6%) 09:12:22 INFO - TinderboxPrint: CPU user
1,220.8 (12.7%) 09:12:22 INFO - TinderboxPrint: Swap in / out
0 / 0 09:12:22 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 4096; Write bytes: 115032064; Read time: 96; Write time: 83304 09:12:22 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 421888; Write bytes: 257159168; Read time: 36; Write time: 149764 09:12:23 INFO - Running post-run listener: _upload_blobber_files 09:12:23 INFO - Blob upload gear active. 09:12:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:12:23 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 09:12:23 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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:12:23 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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:12:23 INFO - (blobuploader) - INFO - Open directory for files ... 09:12:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:12:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:12:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:12:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:12:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:12:26 INFO - (blobuploader) - INFO - Done attempting. 09:12:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:12:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:12:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:12:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:12:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:12:27 INFO - (blobuploader) - INFO - Done attempting. 09:12:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:12:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:12:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:12:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:12:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:12:28 INFO - (blobuploader) - INFO - Done attempting. 09:12:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:12:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:12:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:12:29 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:12:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:12:29 INFO - (blobuploader) - INFO - Done attempting. 09:12:29 INFO - (blobuploader) - INFO - Iteration through files over. 09:12:29 INFO - Return code: 0 09:12:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:12:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:12:29 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8408c7a155b4307774cb70c6d0ef61ab5d2c11acd9a1e99a9f2530cfc9a9408ba22ee53dad8e36acf6bda7a7ef465ba624cdd1f8a42a985f1f40aeac51794f75", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ace83c9e27c1b8c8486ed845ceb955857e122c14ff990164d02f5a4af6211a447f52403077f47be160c2025d3aff0c2634399111ad6fd60ec6a5f12f5f5450fb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dd54f44de4859ff05ec4b70e6e789e6b8c938d37dd30ea59dabcbf9d861d40dc55f86573abab7ea6f6991b1fb444db9f277b0118807e76c69ae465d0e268d1a0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/465ada8c6b3e24e722147b8cea7aff2d2b0ac66982b126e22ae3feac9613118b80a8222044cfbf0b3dfedebce44f42a740abd1e4e7de8da40a1d3eabccc2c035"} 09:12:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:12:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:12:29 INFO - Contents: 09:12:29 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8408c7a155b4307774cb70c6d0ef61ab5d2c11acd9a1e99a9f2530cfc9a9408ba22ee53dad8e36acf6bda7a7ef465ba624cdd1f8a42a985f1f40aeac51794f75", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ace83c9e27c1b8c8486ed845ceb955857e122c14ff990164d02f5a4af6211a447f52403077f47be160c2025d3aff0c2634399111ad6fd60ec6a5f12f5f5450fb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dd54f44de4859ff05ec4b70e6e789e6b8c938d37dd30ea59dabcbf9d861d40dc55f86573abab7ea6f6991b1fb444db9f277b0118807e76c69ae465d0e268d1a0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/465ada8c6b3e24e722147b8cea7aff2d2b0ac66982b126e22ae3feac9613118b80a8222044cfbf0b3dfedebce44f42a740abd1e4e7de8da40a1d3eabccc2c035"} 09:12:29 INFO - Running post-run listener: copy_logs_to_upload_dir 09:12:29 INFO - Copying logs to upload dir... 09:12:29 INFO - mkdir: /builds/slave/test/build/upload/logs 09:12:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.568262 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-03-18 09:12:28.986598) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-18 09:12:28.989649) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=199fb4c72b90437b990b419b000001c5-1489838326.884481-504369783 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8408c7a155b4307774cb70c6d0ef61ab5d2c11acd9a1e99a9f2530cfc9a9408ba22ee53dad8e36acf6bda7a7ef465ba624cdd1f8a42a985f1f40aeac51794f75", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ace83c9e27c1b8c8486ed845ceb955857e122c14ff990164d02f5a4af6211a447f52403077f47be160c2025d3aff0c2634399111ad6fd60ec6a5f12f5f5450fb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dd54f44de4859ff05ec4b70e6e789e6b8c938d37dd30ea59dabcbf9d861d40dc55f86573abab7ea6f6991b1fb444db9f277b0118807e76c69ae465d0e268d1a0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/465ada8c6b3e24e722147b8cea7aff2d2b0ac66982b126e22ae3feac9613118b80a8222044cfbf0b3dfedebce44f42a740abd1e4e7de8da40a1d3eabccc2c035"} build_url:https://queue.taskcluster.net/v1/task/TEKF6SNBREeQZgQt-7oloQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011292 build_url: 'https://queue.taskcluster.net/v1/task/TEKF6SNBREeQZgQt-7oloQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8408c7a155b4307774cb70c6d0ef61ab5d2c11acd9a1e99a9f2530cfc9a9408ba22ee53dad8e36acf6bda7a7ef465ba624cdd1f8a42a985f1f40aeac51794f75", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ace83c9e27c1b8c8486ed845ceb955857e122c14ff990164d02f5a4af6211a447f52403077f47be160c2025d3aff0c2634399111ad6fd60ec6a5f12f5f5450fb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dd54f44de4859ff05ec4b70e6e789e6b8c938d37dd30ea59dabcbf9d861d40dc55f86573abab7ea6f6991b1fb444db9f277b0118807e76c69ae465d0e268d1a0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/465ada8c6b3e24e722147b8cea7aff2d2b0ac66982b126e22ae3feac9613118b80a8222044cfbf0b3dfedebce44f42a740abd1e4e7de8da40a1d3eabccc2c035"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-18 09:12:29.014761) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-18 09:12:29.015053) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=199fb4c72b90437b990b419b000001c5-1489838326.884481-504369783 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004511 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-18 09:12:29.098951) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-18 09:12:29.099261) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-18 09:12:29.099542) ========= ========= Total master_lag: 0.16 =========