builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-047 starttime: 1494605665.35 results: success (0) revision: 8a73de724f644192973e88b7685689b048023676 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-12 09:14:25.346725) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-12 09:14:25.347172) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-12 09:14:25.347446) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-12 09:14:25.371539) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 09:14:25.371873) ========= 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=03627c79f6bb3d1578c4a402000001ba-1494603943.276510-13598989 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-05-12 09:14:25-- 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% 40.0M=0s 2017-05-12 09:14:25 (40.0 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.075469 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 09:14:25.462942) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 09:14:25.463249) ========= 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=03627c79f6bb3d1578c4a402000001ba-1494603943.276510-13598989 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013594 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 09:14:25.512596) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-12 09:14:25.515960) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 8a73de724f644192973e88b7685689b048023676 --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 8a73de724f644192973e88b7685689b048023676 --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=03627c79f6bb3d1578c4a402000001ba-1494603943.276510-13598989 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-05-12 09:14:25,813 truncating revision to first 12 chars 2017-05-12 09:14:25,813 Setting DEBUG logging. 2017-05-12 09:14:25,813 attempt 1/10 2017-05-12 09:14:25,813 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8a73de724f64?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-12 09:14:26,904 unpacking tar archive at: mozilla-central-8a73de724f64/testing/mozharness/ program finished with exit code 0 elapsedTime=1.384812 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-12 09:14:26.913646) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-12 09:14:26.916951) ========= script_repo_revision: 8a73de724f644192973e88b7685689b048023676 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-12 09:14:26.917348) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-12 09:14:26.917611) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-12 09:14:26.927187) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2017-05-12 09:14:26.927463) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=03627c79f6bb3d1578c4a402000001ba-1494603943.276510-13598989 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:14:27 INFO - MultiFileLogger online at 20170512 09:14:27 in /builds/slave/test 09:14:27 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:14:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:14:27 INFO - {'append_to_log': False, 09:14:27 INFO - 'base_work_dir': '/builds/slave/test', 09:14:27 INFO - 'blob_upload_branch': 'Firefox', 09:14:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:14:27 INFO - 'branch': 'Firefox', 09:14:27 INFO - 'buildbot_json_path': 'buildprops.json', 09:14:27 INFO - 'config_files': ('talos/linux_config.py',), 09:14:27 INFO - 'default_actions': ('clobber', 09:14:27 INFO - 'read-buildbot-config', 09:14:27 INFO - 'download-and-extract', 09:14:27 INFO - 'populate-webroot', 09:14:27 INFO - 'create-virtualenv', 09:14:27 INFO - 'install', 09:14:27 INFO - 'run-tests'), 09:14:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:14:27 INFO - 'download_minidump_stackwalk': True, 09:14:27 INFO - 'download_symbols': 'ondemand', 09:14:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:14:27 INFO - 'tooltool.py': '/tools/tooltool.py', 09:14:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:14:27 INFO - '/tools/misc-python/virtualenv.py')}, 09:14:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:14:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:14:27 INFO - 'gecko_profile': False, 09:14:27 INFO - 'gecko_profile_interval': 0, 09:14:27 INFO - 'installer_path': 'installer.exe', 09:14:27 INFO - 'log_level': 'info', 09:14:27 INFO - 'log_name': 'talos', 09:14:27 INFO - 'log_to_console': True, 09:14:27 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:14:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:14:27 INFO - 'opt_config_files': (), 09:14:27 INFO - 'pip_index': False, 09:14:27 INFO - 'suite': 'dromaeojs', 09:14:27 INFO - 'system_bits': '32', 09:14:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:14:27 INFO - 'title': 'talos-linux64-ix-047', 09:14:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:14:27 INFO - 'use_talos_json': True, 09:14:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:14:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:14:27 INFO - 'work_dir': 'build'} 09:14:27 INFO - [mozharness: 2017-05-12 16:14:27.328173Z] Running clobber step. 09:14:27 INFO - Running pre-action listener: _resource_record_pre_action 09:14:27 INFO - Running main action method: clobber 09:14:27 INFO - rmtree: /builds/slave/test/build 09:14:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:14:28 INFO - Running post-action listener: _resource_record_post_action 09:14:28 INFO - [mozharness: 2017-05-12 16:14:28.270592Z] Finished clobber step (success) 09:14:28 INFO - [mozharness: 2017-05-12 16:14:28.270693Z] Running read-buildbot-config step. 09:14:28 INFO - Running pre-action listener: _resource_record_pre_action 09:14:28 INFO - Running main action method: read_buildbot_config 09:14:28 INFO - Using buildbot properties: 09:14:28 INFO - { 09:14:28 INFO - "project": "", 09:14:28 INFO - "product": "firefox", 09:14:28 INFO - "who": "ffxbld@noreply.mozilla.org", 09:14:28 INFO - "installer_path": "public/build/target.tar.bz2", 09:14:28 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 09:14:28 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 09:14:28 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 09:14:28 INFO - "stage_platform": "linux64-pgo", 09:14:28 INFO - "basedir": "/builds/slave/test", 09:14:28 INFO - "buildnumber": 179, 09:14:28 INFO - "platform": "ubuntu64_hw", 09:14:28 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 09:14:28 INFO - "slavebuilddir": "test", 09:14:28 INFO - "taskId": "ZekSa9CKQN2gDev8CY7YqQ", 09:14:28 INFO - "branch": "mozilla-central", 09:14:28 INFO - "script_repo_revision": "production", 09:14:28 INFO - "revision": "8a73de724f644192973e88b7685689b048023676", 09:14:28 INFO - "slavename": "talos-linux64-ix-047", 09:14:28 INFO - "repo_path": "mozilla-central" 09:14:28 INFO - } 09:14:28 INFO - Finding installer, test and symbols from parent task. 09:14:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZekSa9CKQN2gDev8CY7YqQ'}, attempt #1 09:14:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZekSa9CKQN2gDev8CY7YqQ'}, attempt #1 09:14:29 INFO - Task dependencies: RSLPZs3wTpaNujbNO4fCfw 09:14:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RSLPZs3wTpaNujbNO4fCfw'}, attempt #1 09:14:29 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/RSLPZs3wTpaNujbNO4fCfw/artifacts/public/build/target.tar.bz2 09:14:29 INFO - Set test_url: https://queue.taskcluster.net/v1/task/RSLPZs3wTpaNujbNO4fCfw/artifacts/public/build/test_packages.json 09:14:29 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/RSLPZs3wTpaNujbNO4fCfw/artifacts/public/build/target.crashreporter-symbols.zip 09:14:29 INFO - Running post-action listener: _resource_record_post_action 09:14:29 INFO - [mozharness: 2017-05-12 16:14:29.671721Z] Finished read-buildbot-config step (success) 09:14:29 INFO - [mozharness: 2017-05-12 16:14:29.671937Z] Running download-and-extract step. 09:14:29 INFO - Running pre-action listener: _resource_record_pre_action 09:14:29 INFO - Running main action method: download_and_extract 09:14:29 INFO - mkdir: /builds/slave/test/build/tests 09:14:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:14:29 INFO - trying https://queue.taskcluster.net/v1/task/RSLPZs3wTpaNujbNO4fCfw/artifacts/public/build/target.test_packages.json 09:14:29 INFO - Downloading https://queue.taskcluster.net/v1/task/RSLPZs3wTpaNujbNO4fCfw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 09:14:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RSLPZs3wTpaNujbNO4fCfw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 09:14:30 INFO - Downloaded 986 bytes. 09:14:30 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 09:14:30 INFO - Using the following test package requirements: 09:14:30 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 09:14:30 INFO - u'common': [u'target.common.tests.zip'], 09:14:30 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 09:14:30 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 09:14:30 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 09:14:30 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 09:14:30 INFO - u'mozbase': [u'target.common.tests.zip'], 09:14:30 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 09:14:30 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 09:14:30 INFO - u'web-platform': [u'target.common.tests.zip', 09:14:30 INFO - u'target.web-platform.tests.zip'], 09:14:30 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 09:14:30 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 09:14:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RSLPZs3wTpaNujbNO4fCfw/artifacts/public/build/target.common.tests.zip 09:14:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RSLPZs3wTpaNujbNO4fCfw/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:14:30 INFO - Fetch https://queue.taskcluster.net/v1/task/RSLPZs3wTpaNujbNO4fCfw/artifacts/public/build/target.common.tests.zip into memory 09:14:31 INFO - Content-Length response header: 64596266 09:14:31 INFO - Bytes received: 64596266 09:14:42 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 09:14:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RSLPZs3wTpaNujbNO4fCfw/artifacts/public/build/target.common.tests.zip 09:14:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RSLPZs3wTpaNujbNO4fCfw/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:14:42 INFO - Fetch https://queue.taskcluster.net/v1/task/RSLPZs3wTpaNujbNO4fCfw/artifacts/public/build/target.common.tests.zip into memory 09:14:43 INFO - Content-Length response header: 64596266 09:14:43 INFO - Bytes received: 64596266 09:14:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RSLPZs3wTpaNujbNO4fCfw/artifacts/public/build/target.talos.tests.zip 09:14:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RSLPZs3wTpaNujbNO4fCfw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 09:14:54 INFO - Fetch https://queue.taskcluster.net/v1/task/RSLPZs3wTpaNujbNO4fCfw/artifacts/public/build/target.talos.tests.zip into memory 09:14:55 INFO - Content-Length response header: 14409411 09:14:55 INFO - Bytes received: 14409411 09:14:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:14:55 INFO - trying https://queue.taskcluster.net/v1/task/RSLPZs3wTpaNujbNO4fCfw/artifacts/public/build/target.tar.bz2 09:14:55 INFO - Downloading https://queue.taskcluster.net/v1/task/RSLPZs3wTpaNujbNO4fCfw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 09:14:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RSLPZs3wTpaNujbNO4fCfw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 09:14:57 INFO - Downloaded 68643727 bytes. 09:14:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RSLPZs3wTpaNujbNO4fCfw/artifacts/public/build/target.tar.bz2 09:14:57 INFO - mkdir: /builds/slave/test/properties 09:14:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:14:57 INFO - Writing to file /builds/slave/test/properties/build_url 09:14:57 INFO - Contents: 09:14:57 INFO - build_url:https://queue.taskcluster.net/v1/task/RSLPZs3wTpaNujbNO4fCfw/artifacts/public/build/target.tar.bz2 09:14:57 INFO - Running post-action listener: _resource_record_post_action 09:14:57 INFO - Running post-action listener: set_extra_try_arguments 09:14:57 INFO - [mozharness: 2017-05-12 16:14:57.938098Z] Finished download-and-extract step (success) 09:14:57 INFO - [mozharness: 2017-05-12 16:14:57.938323Z] Running populate-webroot step. 09:14:57 INFO - Running pre-action listener: _resource_record_pre_action 09:14:57 INFO - Running main action method: populate_webroot 09:14:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:14:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:14:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:14:57 INFO - '--tppagecycles', 09:14:57 INFO - '7'], 09:14:57 INFO - 'tests': ['tsvgm']}}, 09:14:57 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:14:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:14:57 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:14:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:14:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:14:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:14:57 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:14:57 INFO - 'talos_options': ['--disable-e10s'], 09:14:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:14:57 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:14:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:14:57 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:14:57 INFO - 'talos_options': ['--disable-e10s'], 09:14:57 INFO - 'tests': ['damp', 'tps']}, 09:14:57 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:14:57 INFO - 'tests': ['damp', 'tps']}, 09:14:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:14:57 INFO - 'tests': ['dromaeo_dom']}, 09:14:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:14:57 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:14:57 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 09:14:57 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 09:14:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:14:57 INFO - 'tests': ['a11yr', 09:14:57 INFO - 'ts_paint', 09:14:57 INFO - 'tpaint', 09:14:57 INFO - 'sessionrestore', 09:14:57 INFO - 'sessionrestore_no_auto_restore', 09:14:57 INFO - 'tabpaint']}, 09:14:57 INFO - 'other-e10s': {'tests': ['a11yr', 09:14:57 INFO - 'ts_paint', 09:14:57 INFO - 'tpaint', 09:14:57 INFO - 'sessionrestore', 09:14:57 INFO - 'sessionrestore_no_auto_restore', 09:14:57 INFO - 'tabpaint']}, 09:14:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:14:57 INFO - 'ts_paint', 09:14:57 INFO - 'tpaint', 09:14:57 INFO - 'sessionrestore', 09:14:57 INFO - 'sessionrestore_no_auto_restore', 09:14:57 INFO - 'tabpaint']}, 09:14:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:14:57 INFO - 'ts_paint', 09:14:57 INFO - 'tpaint', 09:14:57 INFO - 'sessionrestore', 09:14:57 INFO - 'sessionrestore_no_auto_restore', 09:14:57 INFO - 'tabpaint']}, 09:14:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:14:57 INFO - 'tests': ['a11yr', 09:14:57 INFO - 'ts_paint', 09:14:57 INFO - 'tpaint', 09:14:57 INFO - 'sessionrestore', 09:14:57 INFO - 'sessionrestore_no_auto_restore', 09:14:57 INFO - 'tabpaint']}, 09:14:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:14:57 INFO - 'tests': ['a11yr', 09:14:57 INFO - 'ts_paint', 09:14:57 INFO - 'tpaint', 09:14:57 INFO - 'sessionrestore', 09:14:57 INFO - 'sessionrestore_no_auto_restore', 09:14:57 INFO - 'tabpaint']}, 09:14:57 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 09:14:57 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 09:14:57 INFO - 'bloom_basic_ref']}, 09:14:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:14:57 INFO - 'tests': ['tsvgx', 09:14:57 INFO - 'tsvgr_opacity', 09:14:57 INFO - 'tart', 09:14:57 INFO - 'tscrollx', 09:14:57 INFO - 'cart', 09:14:57 INFO - 'tsvg_static']}, 09:14:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:14:57 INFO - 'tsvgr_opacity', 09:14:57 INFO - 'tart', 09:14:57 INFO - 'tscrollx', 09:14:57 INFO - 'cart', 09:14:57 INFO - 'tsvg_static']}, 09:14:57 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:14:57 INFO - 'talos_options': ['--disable-e10s'], 09:14:57 INFO - 'tests': ['tp5o']}, 09:14:57 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:14:57 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:14:57 INFO - 'talos_options': ['--disable-e10s', 09:14:57 INFO - '--xperf_path', 09:14:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:14:57 INFO - 'tests': ['tp5n']}, 09:14:57 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:14:57 INFO - 'talos_options': ['--xperf_path', 09:14:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:14:57 INFO - 'tests': ['tp5n']}}, 09:14:57 INFO - 'talos.zip': {'path': '', 09:14:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:14:57 INFO - Running post-action listener: _resource_record_post_action 09:14:57 INFO - [mozharness: 2017-05-12 16:14:57.958782Z] Finished populate-webroot step (success) 09:14:57 INFO - [mozharness: 2017-05-12 16:14:57.958871Z] Running create-virtualenv step. 09:14:57 INFO - Running pre-action listener: _resource_record_pre_action 09:14:57 INFO - Running main action method: create_virtualenv 09:14:57 INFO - Creating virtualenv /builds/slave/test/build/venv 09:14: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 09:14:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:14:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:14:58 INFO - Using real prefix '/usr' 09:14:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:14:58 INFO - Installing distribute.............................................................................................................................................................................................done. 09:15:00 INFO - Installing pip.................done. 09:15:00 INFO - Return code: 0 09:15:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:15:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:15:00 INFO - Reading from file tmpfile_stdout 09:15:00 INFO - Output received: 09:15: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) 09:15:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:15:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:15:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:15:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15: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/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 0x22461f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2412a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25b8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25b5a30>, '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 0x25b67f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25b6ca0>, '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': '03627c79f6bb3d1578c4a402000001ba-1494603943.276510-13598989', '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 09:15:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 09:15:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:15:00 INFO - Using env: {'DISPLAY': ':0', 09:15:00 INFO - 'HOME': '/home/cltbld', 09:15:00 INFO - 'LANG': 'en_US.UTF-8', 09:15:00 INFO - 'LANGUAGE': 'en_US:en', 09:15:00 INFO - 'LOGNAME': 'cltbld', 09:15:00 INFO - 'MAIL': '/var/mail/cltbld', 09:15:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:00 INFO - 'MOZ_NO_REMOTE': '1', 09:15:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:15:00 INFO - 'NO_EM_RESTART': '1', 09:15:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:15:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:00 INFO - 'PWD': '/builds/slave/test', 09:15:00 INFO - 'SHELL': '/bin/bash', 09:15:00 INFO - 'SHLVL': '1', 09:15:00 INFO - 'TERM': 'linux', 09:15:00 INFO - 'TMOUT': '86400', 09:15:00 INFO - 'USER': 'cltbld', 09:15:00 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1494603943.276510-13598989', 09:15:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:00 INFO - '_': '/tools/buildbot/bin/python'} 09:15:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15: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 09:15:00 INFO - Cleaning up... 09:15:00 INFO - Return code: 0 09:15:00 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:15:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:15:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:15:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15: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/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 0x22461f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2412a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25b8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25b5a30>, '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 0x25b67f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25b6ca0>, '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': '03627c79f6bb3d1578c4a402000001ba-1494603943.276510-13598989', '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 09:15:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 09:15:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:15:00 INFO - Using env: {'DISPLAY': ':0', 09:15:00 INFO - 'HOME': '/home/cltbld', 09:15:00 INFO - 'LANG': 'en_US.UTF-8', 09:15:00 INFO - 'LANGUAGE': 'en_US:en', 09:15:00 INFO - 'LOGNAME': 'cltbld', 09:15:00 INFO - 'MAIL': '/var/mail/cltbld', 09:15:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:00 INFO - 'MOZ_NO_REMOTE': '1', 09:15:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:15:00 INFO - 'NO_EM_RESTART': '1', 09:15:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:15:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:00 INFO - 'PWD': '/builds/slave/test', 09:15:00 INFO - 'SHELL': '/bin/bash', 09:15:00 INFO - 'SHLVL': '1', 09:15:00 INFO - 'TERM': 'linux', 09:15:00 INFO - 'TMOUT': '86400', 09:15:00 INFO - 'USER': 'cltbld', 09:15:00 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1494603943.276510-13598989', 09:15:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:00 INFO - '_': '/tools/buildbot/bin/python'} 09:15:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:01 INFO - Downloading/unpacking psutil>=3.1.1 09:15:01 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 09:15:01 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 09:15:01 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 09:15:01 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 09:15:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:15:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:15:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:15:02 INFO - Installing collected packages: psutil 09:15:02 INFO - Running setup.py install for psutil 09:15:02 INFO - building 'psutil._psutil_linux' extension 09:15: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 09:15: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 09:15:02 INFO - building 'psutil._psutil_posix' extension 09:15: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 09:15: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_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 09:15:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:15:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:15:02 INFO - Successfully installed psutil 09:15:02 INFO - Cleaning up... 09:15:02 INFO - Return code: 0 09:15:02 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:15:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:15:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:15:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:02 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/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 0x22461f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2412a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25b8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25b5a30>, '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 0x25b67f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25b6ca0>, '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': '03627c79f6bb3d1578c4a402000001ba-1494603943.276510-13598989', '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 09:15:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 09:15:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:15:02 INFO - Using env: {'DISPLAY': ':0', 09:15:02 INFO - 'HOME': '/home/cltbld', 09:15:02 INFO - 'LANG': 'en_US.UTF-8', 09:15:02 INFO - 'LANGUAGE': 'en_US:en', 09:15:02 INFO - 'LOGNAME': 'cltbld', 09:15:02 INFO - 'MAIL': '/var/mail/cltbld', 09:15:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:02 INFO - 'MOZ_NO_REMOTE': '1', 09:15:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:15:02 INFO - 'NO_EM_RESTART': '1', 09:15:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:15:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:02 INFO - 'PWD': '/builds/slave/test', 09:15:02 INFO - 'SHELL': '/bin/bash', 09:15:02 INFO - 'SHLVL': '1', 09:15:02 INFO - 'TERM': 'linux', 09:15:02 INFO - 'TMOUT': '86400', 09:15:02 INFO - 'USER': 'cltbld', 09:15:02 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1494603943.276510-13598989', 09:15:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:02 INFO - '_': '/tools/buildbot/bin/python'} 09:15:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:03 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:15: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 09:15: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 09:15: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 09:15: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 09:15:04 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:15:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:15:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:15:04 INFO - Installing collected packages: mozsystemmonitor 09:15:04 INFO - Running setup.py install for mozsystemmonitor 09:15:04 INFO - Successfully installed mozsystemmonitor 09:15:04 INFO - Cleaning up... 09:15:04 INFO - Return code: 0 09:15:04 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:15:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:15:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:15:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:04 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/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 0x22461f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2412a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25b8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25b5a30>, '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 0x25b67f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25b6ca0>, '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': '03627c79f6bb3d1578c4a402000001ba-1494603943.276510-13598989', '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 09:15:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 09:15:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:15:04 INFO - Using env: {'DISPLAY': ':0', 09:15:04 INFO - 'HOME': '/home/cltbld', 09:15:04 INFO - 'LANG': 'en_US.UTF-8', 09:15:04 INFO - 'LANGUAGE': 'en_US:en', 09:15:04 INFO - 'LOGNAME': 'cltbld', 09:15:04 INFO - 'MAIL': '/var/mail/cltbld', 09:15:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:04 INFO - 'MOZ_NO_REMOTE': '1', 09:15:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:15:04 INFO - 'NO_EM_RESTART': '1', 09:15:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:15:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:04 INFO - 'PWD': '/builds/slave/test', 09:15:04 INFO - 'SHELL': '/bin/bash', 09:15:04 INFO - 'SHLVL': '1', 09:15:04 INFO - 'TERM': 'linux', 09:15:04 INFO - 'TMOUT': '86400', 09:15:04 INFO - 'USER': 'cltbld', 09:15:04 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1494603943.276510-13598989', 09:15:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:04 INFO - '_': '/tools/buildbot/bin/python'} 09:15:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:04 INFO - Downloading/unpacking jsonschema==2.5.1 09:15:04 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 09:15:04 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 09:15:04 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 09:15:04 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 09:15:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:15:05 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:15: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 09:15: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 09:15: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 09:15: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 09:15:05 INFO - Downloading functools32-3.2.3-2.tar.gz 09:15:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:15:05 INFO - warning: no files found matching '*.txt' 09:15:05 INFO - no previously-included directories found matching 'build' 09:15:05 INFO - no previously-included directories found matching 'dist' 09:15:05 INFO - no previously-included directories found matching '.git*' 09:15:05 INFO - Installing collected packages: jsonschema, functools32 09:15:05 INFO - Running setup.py install for functools32 09:15:05 INFO - warning: no files found matching '*.txt' 09:15:05 INFO - no previously-included directories found matching 'build' 09:15:05 INFO - no previously-included directories found matching 'dist' 09:15:05 INFO - no previously-included directories found matching '.git*' 09:15:06 INFO - Successfully installed jsonschema functools32 09:15:06 INFO - Cleaning up... 09:15:06 INFO - Return code: 0 09:15:06 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:15:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:15:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:15:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x22461f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2412a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25b8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25b5a30>, '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 0x25b67f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25b6ca0>, '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': '03627c79f6bb3d1578c4a402000001ba-1494603943.276510-13598989', '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 09:15:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 09:15:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:15:06 INFO - Using env: {'DISPLAY': ':0', 09:15:06 INFO - 'HOME': '/home/cltbld', 09:15:06 INFO - 'LANG': 'en_US.UTF-8', 09:15:06 INFO - 'LANGUAGE': 'en_US:en', 09:15:06 INFO - 'LOGNAME': 'cltbld', 09:15:06 INFO - 'MAIL': '/var/mail/cltbld', 09:15:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:06 INFO - 'MOZ_NO_REMOTE': '1', 09:15:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:15:06 INFO - 'NO_EM_RESTART': '1', 09:15:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:15:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:06 INFO - 'PWD': '/builds/slave/test', 09:15:06 INFO - 'SHELL': '/bin/bash', 09:15:06 INFO - 'SHLVL': '1', 09:15:06 INFO - 'TERM': 'linux', 09:15:06 INFO - 'TMOUT': '86400', 09:15:06 INFO - 'USER': 'cltbld', 09:15:06 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1494603943.276510-13598989', 09:15:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:06 INFO - '_': '/tools/buildbot/bin/python'} 09:15:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:06 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:15:06 INFO - Cleaning up... 09:15:06 INFO - Return code: 0 09:15:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:15:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:15:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:15:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x22461f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2412a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25b8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25b5a30>, '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 0x25b67f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25b6ca0>, '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': '03627c79f6bb3d1578c4a402000001ba-1494603943.276510-13598989', '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 09:15:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 09:15:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:15:06 INFO - Using env: {'DISPLAY': ':0', 09:15:06 INFO - 'HOME': '/home/cltbld', 09:15:06 INFO - 'LANG': 'en_US.UTF-8', 09:15:06 INFO - 'LANGUAGE': 'en_US:en', 09:15:06 INFO - 'LOGNAME': 'cltbld', 09:15:06 INFO - 'MAIL': '/var/mail/cltbld', 09:15:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:06 INFO - 'MOZ_NO_REMOTE': '1', 09:15:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:15:06 INFO - 'NO_EM_RESTART': '1', 09:15:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:15:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:06 INFO - 'PWD': '/builds/slave/test', 09:15:06 INFO - 'SHELL': '/bin/bash', 09:15:06 INFO - 'SHLVL': '1', 09:15:06 INFO - 'TERM': 'linux', 09:15:06 INFO - 'TMOUT': '86400', 09:15:06 INFO - 'USER': 'cltbld', 09:15:06 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1494603943.276510-13598989', 09:15:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:06 INFO - '_': '/tools/buildbot/bin/python'} 09:15:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:06 INFO - Downloading/unpacking blobuploader==1.2.4 09:15:06 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 09:15:06 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 09:15:06 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 09:15:06 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 09:15:07 INFO - Downloading blobuploader-1.2.4.tar.gz 09:15:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:15:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:15: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 09:15: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 09:15: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 09:15: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 09:15:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:15:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:15: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 09:15: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 09:15: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 09:15: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 09:15:08 INFO - Downloading docopt-0.6.1.tar.gz 09:15:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:15:08 INFO - Installing collected packages: blobuploader, requests, docopt 09:15:08 INFO - Running setup.py install for blobuploader 09:15:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:15:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:15:08 INFO - Running setup.py install for requests 09:15:08 INFO - Running setup.py install for docopt 09:15:08 INFO - Successfully installed blobuploader requests docopt 09:15:08 INFO - Cleaning up... 09:15:08 INFO - Return code: 0 09:15:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:15:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:15:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:15:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:08 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/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 0x22461f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2412a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25b8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25b5a30>, '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 0x25b67f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25b6ca0>, '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': '03627c79f6bb3d1578c4a402000001ba-1494603943.276510-13598989', '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 09:15:08 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:15:08 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:15:08 INFO - Using env: {'DISPLAY': ':0', 09:15:08 INFO - 'HOME': '/home/cltbld', 09:15:08 INFO - 'LANG': 'en_US.UTF-8', 09:15:08 INFO - 'LANGUAGE': 'en_US:en', 09:15:08 INFO - 'LOGNAME': 'cltbld', 09:15:08 INFO - 'MAIL': '/var/mail/cltbld', 09:15:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:08 INFO - 'MOZ_NO_REMOTE': '1', 09:15:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:15:08 INFO - 'NO_EM_RESTART': '1', 09:15:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:15:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:08 INFO - 'PWD': '/builds/slave/test', 09:15:08 INFO - 'SHELL': '/bin/bash', 09:15:08 INFO - 'SHLVL': '1', 09:15:08 INFO - 'TERM': 'linux', 09:15:08 INFO - 'TMOUT': '86400', 09:15:08 INFO - 'USER': 'cltbld', 09:15:08 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1494603943.276510-13598989', 09:15:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:08 INFO - '_': '/tools/buildbot/bin/python'} 09:15:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:15:08 INFO - Running setup.py (path:/tmp/pip-PqwrNJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:15:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:15:09 INFO - Running setup.py (path:/tmp/pip-zUBDlU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:15:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:15:09 INFO - Running setup.py (path:/tmp/pip-CxYnmp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:15:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:15:09 INFO - Running setup.py (path:/tmp/pip-Pm2qQb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:15:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:15:09 INFO - Running setup.py (path:/tmp/pip-LdWxlQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:15:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:15:09 INFO - Running setup.py (path:/tmp/pip-7IM519-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:15:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:15:09 INFO - Running setup.py (path:/tmp/pip-bZvr8S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:15:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:15:09 INFO - Running setup.py (path:/tmp/pip-Ojclsq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:15:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:15:09 INFO - Running setup.py (path:/tmp/pip-83sGpl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:15:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:15:09 INFO - Running setup.py (path:/tmp/pip-UTiDRO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:15:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:15:09 INFO - Running setup.py (path:/tmp/pip-MHySM8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:15:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:15:09 INFO - Running setup.py (path:/tmp/pip-1JP3XC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:15:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:15:09 INFO - Running setup.py (path:/tmp/pip-3lQqZ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:15:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:15:09 INFO - Running setup.py (path:/tmp/pip-rFLTQE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:15:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:15:09 INFO - Running setup.py (path:/tmp/pip-f0o721-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:15:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:15:10 INFO - Running setup.py (path:/tmp/pip-dF9Z9C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:15:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:15:10 INFO - Running setup.py (path:/tmp/pip-KsM2xh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:15:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:15:10 INFO - Running setup.py install for manifestparser 09:15:10 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:15:10 INFO - Running setup.py install for mozcrash 09:15:10 INFO - Running setup.py install for mozdebug 09:15:10 INFO - Running setup.py install for mozdevice 09:15:10 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:15:10 INFO - Running setup.py install for mozfile 09:15:10 INFO - Running setup.py install for mozhttpd 09:15:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:15:10 INFO - Running setup.py install for mozinfo 09:15:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:15:10 INFO - Running setup.py install for mozInstall 09:15:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:15:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:15:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:15:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:15:10 INFO - Running setup.py install for mozleak 09:15:11 INFO - Running setup.py install for mozlog 09:15:11 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:15:11 INFO - Running setup.py install for moznetwork 09:15:11 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:15:11 INFO - Running setup.py install for mozprocess 09:15:11 INFO - Running setup.py install for mozprofile 09:15:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:15:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:15:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:15:11 INFO - Running setup.py install for mozrunner 09:15:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:15:11 INFO - Running setup.py install for mozscreenshot 09:15:11 INFO - Running setup.py install for moztest 09:15:11 INFO - Running setup.py install for mozversion 09:15:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:15:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:15:11 INFO - Cleaning up... 09:15:11 INFO - Return code: 0 09:15:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:15:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:15:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:15:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:11 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/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 0x22461f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2412a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25b8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25b5a30>, '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 0x25b67f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25b6ca0>, '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': '03627c79f6bb3d1578c4a402000001ba-1494603943.276510-13598989', '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 09:15:11 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:15:11 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:15:11 INFO - Using env: {'DISPLAY': ':0', 09:15:11 INFO - 'HOME': '/home/cltbld', 09:15:11 INFO - 'LANG': 'en_US.UTF-8', 09:15:11 INFO - 'LANGUAGE': 'en_US:en', 09:15:11 INFO - 'LOGNAME': 'cltbld', 09:15:11 INFO - 'MAIL': '/var/mail/cltbld', 09:15:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:11 INFO - 'MOZ_NO_REMOTE': '1', 09:15:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:15:11 INFO - 'NO_EM_RESTART': '1', 09:15:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:15:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:11 INFO - 'PWD': '/builds/slave/test', 09:15:11 INFO - 'SHELL': '/bin/bash', 09:15:11 INFO - 'SHLVL': '1', 09:15:11 INFO - 'TERM': 'linux', 09:15:11 INFO - 'TMOUT': '86400', 09:15:11 INFO - 'USER': 'cltbld', 09:15:11 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1494603943.276510-13598989', 09:15:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:11 INFO - '_': '/tools/buildbot/bin/python'} 09:15:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:15:12 INFO - Running setup.py (path:/tmp/pip-gxVkGq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:15:12 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)) 09:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:15:12 INFO - Running setup.py (path:/tmp/pip-bYa62h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:15:12 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)) 09:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:15:12 INFO - Running setup.py (path:/tmp/pip-h0xrVH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:15:12 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)) 09:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:15:12 INFO - Running setup.py (path:/tmp/pip-SHUIhU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:15:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:15:12 INFO - Running setup.py (path:/tmp/pip-LzzJRK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:15:12 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)) 09:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:15:12 INFO - Running setup.py (path:/tmp/pip-Ok585E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:15:12 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)) 09:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:15:12 INFO - Running setup.py (path:/tmp/pip-2PRxVw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:15:12 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)) 09:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:15:12 INFO - Running setup.py (path:/tmp/pip-B3vhfV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:15:12 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)) 09:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:15:12 INFO - Running setup.py (path:/tmp/pip-ahmVax-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:15:12 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)) 09:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:15:12 INFO - Running setup.py (path:/tmp/pip-DAyYGN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:15:12 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)) 09:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:15:12 INFO - Running setup.py (path:/tmp/pip-RgNc_k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:15:12 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)) 09:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:15:13 INFO - Running setup.py (path:/tmp/pip-3mcdGz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:15:13 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)) 09:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:15:13 INFO - Running setup.py (path:/tmp/pip-DTFeA9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:15:13 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)) 09:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:15:13 INFO - Running setup.py (path:/tmp/pip-DLCGbt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:15:13 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)) 09:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:15:13 INFO - Running setup.py (path:/tmp/pip-ZzhWlf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:15:13 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)) 09:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:15:13 INFO - Running setup.py (path:/tmp/pip-fdVTU1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:15:13 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)) 09:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:15:13 INFO - Running setup.py (path:/tmp/pip-U3giim-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:15:13 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)) 09:15:13 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)) 09:15:13 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)) 09:15:13 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)) 09:15:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:15:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:15:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:15:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:15:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:15:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:15:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:15:14 INFO - Downloading blessings-1.6.tar.gz 09:15:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:15:14 INFO - Installing collected packages: blessings 09:15:14 INFO - Running setup.py install for blessings 09:15:14 INFO - Successfully installed blessings 09:15:14 INFO - Cleaning up... 09:15:14 INFO - Return code: 0 09:15:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:15:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:15:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:15:14 INFO - Reading from file tmpfile_stdout 09:15:14 INFO - Current package versions: 09:15:14 INFO - argparse == 1.2.1 09:15:14 INFO - blessings == 1.6 09:15:14 INFO - blobuploader == 1.2.4 09:15:14 INFO - docopt == 0.6.1 09:15:14 INFO - functools32 == 3.2.3-2 09:15:14 INFO - jsonschema == 2.5.1 09:15:14 INFO - manifestparser == 1.1 09:15:14 INFO - mozInstall == 1.12 09:15:14 INFO - mozcrash == 1.0 09:15:14 INFO - mozdebug == 0.1 09:15:14 INFO - mozdevice == 0.50 09:15:14 INFO - mozfile == 1.2 09:15:14 INFO - mozhttpd == 0.7 09:15:14 INFO - mozinfo == 0.9 09:15:14 INFO - mozleak == 0.1 09:15:14 INFO - mozlog == 3.4 09:15:14 INFO - moznetwork == 0.27 09:15:14 INFO - mozprocess == 0.25 09:15:14 INFO - mozprofile == 0.28 09:15:14 INFO - mozrunner == 6.13 09:15:14 INFO - mozscreenshot == 0.1 09:15:14 INFO - mozsystemmonitor == 0.3 09:15:14 INFO - moztest == 0.8 09:15:14 INFO - mozversion == 1.4 09:15:14 INFO - psutil == 3.1.1 09:15:14 INFO - requests == 1.2.3 09:15:14 INFO - wsgiref == 0.1.2 09:15:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:15:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:15:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:15:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:14 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/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 0x22461f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2412a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25b8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25b5a30>, '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 0x25b67f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25b6ca0>, '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': '03627c79f6bb3d1578c4a402000001ba-1494603943.276510-13598989', '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 09:15:14 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:15:14 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:15:14 INFO - Using env: {'DISPLAY': ':0', 09:15:14 INFO - 'HOME': '/home/cltbld', 09:15:14 INFO - 'LANG': 'en_US.UTF-8', 09:15:15 INFO - 'LANGUAGE': 'en_US:en', 09:15:15 INFO - 'LOGNAME': 'cltbld', 09:15:15 INFO - 'MAIL': '/var/mail/cltbld', 09:15:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:15 INFO - 'MOZ_NO_REMOTE': '1', 09:15:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:15:15 INFO - 'NO_EM_RESTART': '1', 09:15:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:15:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:15 INFO - 'PWD': '/builds/slave/test', 09:15:15 INFO - 'SHELL': '/bin/bash', 09:15:15 INFO - 'SHLVL': '1', 09:15:15 INFO - 'TERM': 'linux', 09:15:15 INFO - 'TMOUT': '86400', 09:15:15 INFO - 'USER': 'cltbld', 09:15:15 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1494603943.276510-13598989', 09:15:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:15 INFO - '_': '/tools/buildbot/bin/python'} 09:15:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:15 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)) 09:15:15 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)) 09:15:15 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)) 09:15:15 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)) 09:15:15 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)) 09:15:15 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)) 09:15:15 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)) 09:15:15 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)) 09:15:15 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)) 09:15:15 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)) 09:15:15 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)) 09:15:15 INFO - Cleaning up... 09:15:15 INFO - Return code: 0 09:15:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:15:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:15:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:15:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x22461f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2412a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25b8770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25b5a30>, '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 0x25b67f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25b6ca0>, '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': '03627c79f6bb3d1578c4a402000001ba-1494603943.276510-13598989', '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 09:15:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:15:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:15:15 INFO - Using env: {'DISPLAY': ':0', 09:15:15 INFO - 'HOME': '/home/cltbld', 09:15:15 INFO - 'LANG': 'en_US.UTF-8', 09:15:15 INFO - 'LANGUAGE': 'en_US:en', 09:15:15 INFO - 'LOGNAME': 'cltbld', 09:15:15 INFO - 'MAIL': '/var/mail/cltbld', 09:15:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:15 INFO - 'MOZ_NO_REMOTE': '1', 09:15:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:15:15 INFO - 'NO_EM_RESTART': '1', 09:15:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:15:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:15 INFO - 'PWD': '/builds/slave/test', 09:15:15 INFO - 'SHELL': '/bin/bash', 09:15:15 INFO - 'SHLVL': '1', 09:15:15 INFO - 'TERM': 'linux', 09:15:15 INFO - 'TMOUT': '86400', 09:15:15 INFO - 'USER': 'cltbld', 09:15:15 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1494603943.276510-13598989', 09:15:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:15 INFO - '_': '/tools/buildbot/bin/python'} 09:15:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:15 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:15:15 INFO - Cleaning up... 09:15:15 INFO - Return code: 0 09:15:15 INFO - Running post-action listener: _resource_record_post_action 09:15:15 INFO - Running post-action listener: _start_resource_monitoring 09:15:15 INFO - Starting resource monitoring. 09:15:15 INFO - [mozharness: 2017-05-12 16:15:15.496768Z] Finished create-virtualenv step (success) 09:15:15 INFO - [mozharness: 2017-05-12 16:15:15.497260Z] Running install step. 09:15:15 INFO - Running pre-action listener: _resource_record_pre_action 09:15:15 INFO - Running main action method: install 09:15:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:15:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:15:15 INFO - Reading from file tmpfile_stdout 09:15:15 INFO - Detecting whether we're running mozinstall >=1.0... 09:15:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:15:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:15:15 INFO - Reading from file tmpfile_stdout 09:15:15 INFO - Output received: 09:15:15 INFO - Usage: mozinstall [options] installer 09:15:15 INFO - Options: 09:15:15 INFO - -h, --help show this help message and exit 09:15:15 INFO - -d DEST, --destination=DEST 09:15:15 INFO - Directory to install application into. [default: 09:15:15 INFO - "/builds/slave/test"] 09:15:15 INFO - --app=APP Application being installed. [default: firefox] 09:15:15 INFO - mkdir: /builds/slave/test/build/application 09:15:15 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'] 09:15:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 09:15:32 INFO - Reading from file tmpfile_stdout 09:15:32 INFO - Output received: 09:15:32 INFO - /builds/slave/test/build/application/firefox/firefox 09:15:32 INFO - Running post-action listener: _resource_record_post_action 09:15:32 INFO - [mozharness: 2017-05-12 16:15:32.921349Z] Finished install step (success) 09:15:32 INFO - [mozharness: 2017-05-12 16:15:32.921600Z] Running run-tests step. 09:15:32 INFO - Running pre-action listener: _resource_record_pre_action 09:15:32 INFO - Running main action method: run_tests 09:15:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:15:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:15:32 INFO - Python 2.7.3 09:15:32 INFO - Return code: 0 09:15:32 INFO - grabbing minidump binary from tooltool 09:15:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:15:32 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 0x25b5a30>, '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 0x25b67f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25b6ca0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:15:32 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 09:15:32 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 09:15:32 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 09:15:32 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:15:32 INFO - Return code: 0 09:15:32 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:15:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:15:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:15:33 INFO - ENV: RUST_BACKTRACE is now 1 09:15:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:15:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:15:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RSLPZs3wTpaNujbNO4fCfw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:15:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RSLPZs3wTpaNujbNO4fCfw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-047 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:15:33 INFO - Using env: {'DISPLAY': ':0', 09:15:33 INFO - 'HOME': '/home/cltbld', 09:15:33 INFO - 'LANG': 'en_US.UTF-8', 09:15:33 INFO - 'LANGUAGE': 'en_US:en', 09:15:33 INFO - 'LOGNAME': 'cltbld', 09:15:33 INFO - 'MAIL': '/var/mail/cltbld', 09:15:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:15:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:15:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:33 INFO - 'MOZ_NO_REMOTE': '1', 09:15:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:15:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:15:33 INFO - 'NO_EM_RESTART': '1', 09:15:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:15:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:33 INFO - 'PWD': '/builds/slave/test', 09:15:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:15:33 INFO - 'RUST_BACKTRACE': '1', 09:15:33 INFO - 'SHELL': '/bin/bash', 09:15:33 INFO - 'SHLVL': '1', 09:15:33 INFO - 'TERM': 'linux', 09:15:33 INFO - 'TMOUT': '86400', 09:15:33 INFO - 'USER': 'cltbld', 09:15:33 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1494603943.276510-13598989', 09:15:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:33 INFO - '_': '/tools/buildbot/bin/python'} 09:15:33 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RSLPZs3wTpaNujbNO4fCfw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:15:33 INFO - mozversion application_buildid: 20170512151418 09:15:33 INFO - mozversion application_changeset: 8a73de724f644192973e88b7685689b048023676 09:15:33 INFO - mozversion application_display_name: Nightly 09:15:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:15:33 INFO - mozversion application_name: Firefox 09:15:33 INFO - mozversion application_remotingname: firefox 09:15:33 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:15:33 INFO - mozversion application_vendor: Mozilla 09:15:33 INFO - mozversion application_version: 55.0a1 09:15:33 INFO - mozversion platform_buildid: 20170512151418 09:15:33 INFO - mozversion platform_changeset: 8a73de724f644192973e88b7685689b048023676 09:15:33 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:15:33 INFO - mozversion platform_version: 55.0a1 09:15:33 INFO - using testdate: 1494605733 09:15:33 INFO - actual date: 1494605733 09:15:33 INFO - starting webserver on 'localhost:35208' 09:15:33 INFO - SUITE-START | Running 2 tests 09:15:33 INFO - TEST-START | dromaeo_css 09:15:33 INFO - operating with platform_type : linux_ 09:15:33 INFO - Initialising browser for dromaeo_css test... 09:15:33 INFO - TEST-INFO | started process 17143 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpL1tCZX/profile http://localhost:35208/getInfo.html) 09:15:36 INFO - PID 17143 | __metrics Screen width/height:1600/1200 09:15:36 INFO - PID 17143 | colorDepth:24 09:15:36 INFO - PID 17143 | Browser inner width/height: 1024/695 09:15:36 INFO - PID 17143 | __metrics 09:15:38 INFO - PID 17143 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:15:38 INFO - PID 17143 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:15:39 INFO - TEST-INFO | 17143: exit 0 09:15:39 INFO - Browser initialized. 09:15:39 INFO - Running cycle 1/1 for dromaeo_css test... 09:15:39 INFO - TEST-INFO | started process 17239 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpL1tCZX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:15:50 INFO - PID 17239 | RSS: Main: 149209088 09:15:50 INFO - PID 17239 | 09:18:52 INFO - PID 17239 | Cycle 1(1): loaded http://localhost:35208/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35208/tests/dromaeo/cssquery-ext.html) 09:18:55 INFO - PID 17239 | RSS: Main: 262766592 09:18:55 INFO - PID 17239 | 09:21:57 INFO - PID 17239 | Cycle 1(1): loaded http://localhost:35208/tests/dromaeo/cssquery-ext.html (next: http://localhost:35208/tests/dromaeo/cssquery-jquery.html) 09:21:57 INFO - PID 17239 | RSS: Main: 230989824 09:21:57 INFO - PID 17239 | 09:24:59 INFO - PID 17239 | Cycle 1(1): loaded http://localhost:35208/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35208/tests/dromaeo/cssquery-mootools.html) 09:25:00 INFO - PID 17239 | RSS: Main: 213004288 09:25:00 INFO - PID 17239 | 09:28:01 INFO - PID 17239 | Cycle 1(1): loaded http://localhost:35208/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35208/tests/dromaeo/cssquery-prototype.html) 09:28:02 INFO - PID 17239 | RSS: Main: 188157952 09:28:02 INFO - PID 17239 | 09:31:05 INFO - PID 17239 | Cycle 1(1): loaded http://localhost:35208/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35208/tests/dromaeo/cssquery-yui.html) 09:31:05 INFO - PID 17239 | RSS: Main: 178634752 09:31:05 INFO - PID 17239 | 09:34:07 INFO - PID 17239 | Cycle 1(1): loaded http://localhost:35208/tests/dromaeo/cssquery-yui.html (next: http://localhost:35208/tests/dromaeo/cssquery-dojo.html) 09:34:07 INFO - PID 17239 | RSS: Main: 274288640 09:34:07 INFO - PID 17239 | 09:34:07 INFO - PID 17239 | __start_tp_report 09:34:07 INFO - PID 17239 | _x_x_mozilla_page_load 09:34:07 INFO - PID 17239 | _x_x_mozilla_page_load_details 09:34:07 INFO - PID 17239 | |i|pagename|runs| 09:34:07 INFO - PID 17239 | |0;dojo.html;3335.8832440864576;3355.1779813945586;3374.4094783412897;3375.240570871554;3376.79739215647;5607.046802043652;5725.055365864628;5825.533957283418;5890.88218235635;5904.291485021793;5715.714214289279;5793.391693872146;5794.333651630064;5797.449242321973;5808.361080281176;5113.897722045566;5134.512221338967;5136.614753893455;5166.354205724285;5172.017316709813;5204.947950520503;5241.8951620967555;5268.841934741964;5284.339457567804;5337.733113344337;5280.762365693553;5292.629515933886;5294.682319060849;5298.993191293642;5304.814331498398;749.0935502663048;5082.6188035897085;5121.103806833789;5236.528712415901;5272.420033796279;5197.870053248661;5201.349831271091;5202.245674377232;5207.479252074762;5211.452797456251;5690.2887139107615;5799.101139323369;5836.533077353844;5846.824595262106;5874.882502349929;6043.1841701370195;6098.634081955097;6120.051392034242;6121.173641558377;6121.877562448726;6098.595953128956;6103.1394811735445;6112.685862051835;6113.415495409501;6138.907916381258;5734.16854556087;5735.8566035849435;5737.3688894221605;5738.655680659173;5744.195812586241;6135.569531795964;6178.258608966953;6187.319394866561;6197.907031394533;6206.348333425016;6013.789517366871;6017.548683848729;6018.037114061729;6021.819345419644;6027.547933904974;5494.972525137428;6043.969780151159;6106.175666285084;6115.663638499836;6170.475509581646;6193;6260.499160067184;6293.653849038347;6297.4647154992335;6305.4009869062365;6266.373362663789;6279.717412716439;6292.1505596743855;6294.590851594723;6300.2754683212015;4649.767511624405;4706.623470122382;4758.07217592573;4784.617230621542;4814.711117332901;4257.510386305553;4266.274733295348;4282.700210985264;4296.957030429656;4319.308910574356;5915.674637894957;5916.171735956969;5919.260092488439;5919.881602367928;5971.5222782177325;6098.717026081328;6138.339901207151;6317.684115794192;6366;6383.489320854321;5877.118432235114;5916.467517923407;5967.910481342783;5971.134185543072;5980.820575382745;5310.867228319245;5341.946580534222;5343.919841202385;5345.118055520796;5345.1447768356875;6206.813795586139;6473.061406096184;6477.611343319416;6478.967605161944;6499.675016249169;6159.414855588712;6198.504119670506;6302.810915672537;6314.116023756769;6341.524385671185;6506.934930650822;6586.407223349922;6586.571872828251;6588.308227636221;6588.374104460068;6024.216851809237;6241.687915604292;6247.875042499034;6249.500039996698;6251.937480625317;6119.929102025315;6191.473724733268;6194.907076393768;6200.503959683124;6205.47253483459;6050.001749711424;6113.1135985281;6139.093519790236;6140.534923917806;6145.631262124287;5858.179854820243;6155.47678447337;6170.475509581735;6170.629762214281;6176.073588961692;6006.099085137264;6012.729427175875;6016.7292471838;6018.969905150446;6024.036154215304;6058;6060.393960603904;6070.362611925861;6072.180255665428;6073.180120683828;5876.147958546072;5881.676507791942;5883.529317654492;5884.382139875252;5991.370906054802 09:34:07 INFO - PID 17239 | |1;ext.html;796.7251298302085;816.5468165168332;1138.1350173867859;1162.5698491558126;1165.6794381545074;34348.65651343486;37950.81024594876;38315.233695326075;38407.615923840756;38428.61571384289;41031.384529232084;41414.7929260354;41429.79285103578;41433.17133657325;41448.79275603625;5600.719964001804;7254.383377412913;7375.963120184391;7376.7786966391095;7381.29877661624;5730.197772311859;7085.468589855782;7129.215786263507;7132.429405647562;7346.522476039067;7236.348728614424;7250.782476525713;7258.201597824262;7395.741149059784;7489.9251007489775;7207.13514378278;7208.207097219301;7215;7280.053593032898;7332.8900066499045;7347;7368.557886526825;7418.740344087985;7418.96290518544;7420.5547667140145;36575.63424365749;36669.266614667824;36898.26203475942;36938.261234775426;36954.815225923696;3468.3410152071024;3513.139280876176;3513.2270900401872;3513.8243087845503;3516.226430185355;29021.56467652987;29057.709422905922;29094.85452572745;29129.271768205752;29134.27164320888;3736.327461057009;3741.8877433677035;3773.9433908491396;3783.4135708964877;3791.146991926822;183056;184261.07869460701;184317;184402.07798961052;184422;39513.40729889054;40228.79885600612;48051.27923081157;48311.75844120827;48335;49636.75181624141;50031.24953125706;50336;50395.74802125966;50557.24164137541;14684.85315146835;14813.407463701331;14814.555563333117;14819.481318153812;14825.70348593022;11765.11761617882;11789.762074982174;11795.879391457798;11798.88201117995;11804.940975295069;13739.24434156131;13774.449022039207;13783.448662053406;13866.861331386759;13934.930325348309;5152.845414637567;5221.582273418117;5241.528262456397;5272.525472707474;5290.814821481306;1071.2394200118003;1090.2095980414135;1126.8985791278824;1128.0242590159617;1136.4090672850236;25861.35346616312;26143.60784588233;26315;26347.473050538883;26355.868220658773;1175.0071189844568;1186.4008675618757;1208.5830388516124;1236.2273579013117;1240.7394447166016;13317.200967941955;13680.247586382844;13735.587932362045;13832.861671383158;13858.376373063236;236100;236419;237066;237379;237438.56649317738;51952.740236298574;52081;52181;52276.73861630668;52323;1501.677139415031;1511.5767585076196;1528.7171873203492;1549.7133030389414;1556.9143697096767;24619.38451538654;25209.11768088108;25295.873520632278;25705;25802.774653168355;38605.22789544137;38729.41905871358;38741.22517549577;38764.41853372258;38837.2232555353;210843;210925;211095;211410.9429452843;211612;29915.252118697208;30416.695833041387;33498.66501334956;34072.31855362929;34342.656573433946;39930;40487.797561012005;41286;41666.58333416748;41730.58269417267;30983.225419364695;31001.379972399973;31145;31366.843165784026;32626.83686581552;31072.68927310698;31091;31155;32317.192070198435;32584;36521.817390912875;36559.45160822643;36568.45147322739;36600.26799464049;38536.229275414895;32159.51760723638;32612.184695382806;32726.34547309088;32874.50688239631;33332;24550.263492095266;26628.7337126634;26638.866805665846;26741.732582673925;27332.86333568399 09:34:07 INFO - PID 17239 | |2;jquery.html;428.59497774602994;432.89826890680706;443.51656694203314;460.6660171375753;471.18955396717644;18448.907755461238;19536.706949395753;20127.396178114643;20255.291064812736;20293.391198264075;20665.070071846767;20867.478313042182;20945.581088378225;20946.371608851758;20963.580728385423;5489.835304940858;5735.627184233012;5740.483356497914;5746.454086861741;5747.856303592423;5424.213489044087;5598.440155984389;5629.887402251952;5677.432256774331;5690.57320700947;5605.803796867111;5638.492535671789;5641.210230567704;5643.887122257553;5647.322321321427;5433.483819037185;5559.305086864142;5597.63615365;5600.439956004367;5622.550195984311;5074.213496907985;5104.310918026056;5111.821086261963;5112.130937740593;5112.769925353368;25276.368090797692;26211.903047756325;26258.474830503474;26368.60447093295;27312;2093.822025127866;2129.2228336657163;2130.946726331839;2138.4760733620205;2142.475093602062;16898.577535561588;17412.390566330243;17600.66788914235;17606.559836004075;17672;2137.422895818136;2227.7103976483118;2241.4396400899777;2271.4207876991263;2293.3578597992587;109102.90897091007;113307;113579.43210283978;113880.43059784731;116516.99999999916;12377.752444951231;12384.442700078518;12518.624441266777;12595;12805.231686098863;18084.9095754523;18102.3664171756;18104.185311661007;18107.72838407425;18170.182341794385;8509.234168924826;8673.869891951626;8708.956455217683;8792.8681069784;8874;7865.488743231672;7987.400944929153;7989.760307190793;8126.593670316579;8157.3474122071275;7878.1334053254095;8136.227058429558;8150.858879756839;8180.018397792184;8292.253697167283;2230.732312122523;2253.2789507357816;2260.6269965455845;2265.920692775745;2403.26700356393;1028.8096702110133;1066.7813098314828;1068.8877667844765;1070.240129508058;1070.7697844963213;19339.613207735765;20464.488387790127;20571.177152914017;20755.8962205188;20881.58236835255;1059.1579694143174;1064.7125276175389;1065.1478816946412;1066.8079745645703;1074.8334008228737;10771.515281812337;11050.944745276383;11051.944740276247;11153.944230278797;11228.157888158257;430230;434104;443021;448638;449034;91187.54406228059;92198;95466;97513;98097;1147.0231790291414;1186.3890096600257;1204.0608325506118;1206.595790410205;1214.5506162719673;16601.50195494137;17186.140692965553;17456;17566.91216543909;17788.91105544464;44024.55975440333;44230.77884610556;44644.10711785681;45313.32030019618;45370;56830.715846420506;56848.431515684315;57093;57596.424035759104;57820.421795781505;9034.954825225832;9092.454452732858;9387.671431499864;9619.711408657751;9779.511024448606;14188.716225675222;15097.39610415571;15138.772918406454;15699;15736.763948540553;12849.164804287691;12947.287899165636;13118.08173427889;13542.390592423562;13775.38010789537;11365.31808091517;11461;11868.347240901834;12186.87813121893;12563.937180314406;13109.737805244033;13335.799963000369;13336.466541338626;13734.93132534331;13756.243406612732;9610.327277090537;9638.049655997664;9638.590352411897;9893.65372211969;10071.143952763805;12181.390930453264;12457.439415226518;12500.562480313509;12681.048921330752;12743.93628031854 09:34:07 INFO - PID 17239 | |3;mootools.html;1213.8118591618302;1392.1647011792923;1431.7422863884503;1443.2639353929487;1448.3482432905196;9939.155171810396;11418.42907854607;11990.7601847963;12001.879981200185;12016.7596648067;11600;11694.649160525198;11709.063274938006;11727.824082638746;11736.237144585595;2013.2550956146174;2099.9895000525053;2415.5048215115903;2459.0409740201335;2460.1020627471025;2355.49356888269;2442.0353960185716;2448.8285819992607;2449.2407353720314;2450.019992003195;2407.337982054931;2416.4562973331035;2426.2478631624163;2426.5874801283826;2434.087217293515;2392.6411038344213;2401.4356626192916;2407.16952651335;2409.9518009639796;2410.397400649829;2289;2291.2553420138356;2299.2067736630834;2301.8043466305385;2301.873396963166;11359.091272698164;11376.146788990776;11443.198976071679;11452.141089418326;11454.770904581861;6282.151909492251;6531.771388001444;6533.93466065338;6643.468522518187;6653.301403352675;3709.536307961505;3925.685945124412;4107.075907920724;4126.607972242632;4134.565870583606;1324.569514907659;1325.3307079924673;1380.9171449713049;1387.2508845223567;1390.8025319994385;18012.54968625782;18016.099195040195;18039.098045097824;18049.54876128094;18074.457766267034;5621.353544342372;5667.3482549507335;5764.308283006066;5771.769129234867;5824.970875145682;14623.707525849424;14629.341679624442;14648.926755366156;14655.560333190022;14655.780163297559;6590.604563726191;6630.900536491957;6640.867182656417;6641.501887358468;6669.533132680763;5978.701064946736;5998.850028749229;6040.6979651018155;6107.969460152671;6159.938400616026;4461.866144015685;4527.253003254426;4598.747068911242;4634.304854271886;4656.837010704609;1384.1694983010113;1390.1728471559406;1407.6410515318528;1417.482618844135;1421.8933579981542;861.2765277167084;878.1701292278735;891.8662200669952;899.0604817965242;922.5940586142076;7112.822179445555;7160.706075205464;7489.176190619137;7766.106897706836;7941.166177551274;966.6230170233614;983.6606370802061;985.5909797434128;991.4695637833869;996.3922007575374;4507.211238033331;4665.766711664403;4715.504871988392;4726.361941137971;4853.417589889236;15065.623359416102;15321.080735155703;15384.38462461489;15551.766723499157;15883.047017179006;15046.54860354191;15372.692546149014;15448.536543903701;15459.845401546065;15541.533753987398;9540.761480962892;9666.371685840399;9799.11807937289;9818.459984700767;9841.55712992917;6888.345607167311;7240.746573869889;7596.886046709194;7637.732679356418;7669.808254793675;13541.932290338485;13761.17432954026;13861.376238069513;13877.236751978739;13941.651458713208;13612.86387136116;14024.08843425174;14035.368408421442;14123.43506259738;14223.64440888986;9442.669506567237;9673.177779888789;9694.982026887075;9840;9842.557084931052;11062.336259824437;11135.610253641084;11168.776624467302;11191.72020699489;11210.495527701436;8845.380823342304;8983.46099234048;9003.954980225057;9243.2143267823;9269.629214831331;8819.955900220715;8934.955325223333;9001.864972025294;9041.231495322709;9056.909430905607;8406.537640429835;8590.570471476527;8595.52724600153;8601.741947741577;8609;6299;6351.68241587928;6352.333005034405;6409.7436102555375;6581.473482121514;6295.9370406297185;6403.679816009274;6410.423061924449;6430.678466076584;6705.966470167813 09:34:07 INFO - PID 17239 | |4;prototype.html;388.7045845157679;409.5331322292589;409.7725762201978;412.6059613069517;412.9421880936669;5529.391766905637;5731.392216541474;5782.76868925243;5837.532997360212;5844.795432159875;5544.16837474379;5681;5731.598788084836;5734.971325143369;5742;4553.2942393929;4556.270996640538;4563.885902852422;4571.839985600504;4577.56513131252;4431.512533621315;4521.457425108992;4550.749708766017;4552.521985191557;4562.520935301779;4489.25927222008;4513.255312873372;4516.119356725423;4517.435320584927;4521.1409832131785;4528.637708983307;4529.320601909707;4532.252178390561;4534.433195850502;4538.773061346952;4044.312466880637;4078.490188726409;4089.9182016359805;4113.773742444165;4117.949922769674;5598.832035038955;5599.160125981135;5601.663900166003;5612.793249451388;5617.4382561743905;5288.365396152448;5330.227117068042;5336.706481143536;5357.142857142878;5372.838814835562;6010.338862725097;6047.939520604826;6071.150038994544;6086.786962456337;6092.4768807798055;4575.107853968478;4577.9542204577865;4593.977030114861;4596.402467679214;4600.309953506967;6138.570300078996;6212.844678883019;6273.905891411679;6274.121622972787;6299.559030867841;4689.788959496831;4715.646326525524;4727.338172655831;4751.073540659608;4751.239801631722;4776.331313616097;4797.544233297831;4798.808047678054;4800.759962001886;4802.471728109905;5253.212018197301;5291;5324.387695415076;5331.866703332447;5344.518993290622;5818.301803783488;5899.852503687356;6087.65625234288;6119.694015299217;6142.539309551801;1597.8002749656293;1646.5554300338842;1661.3271624991899;1662.168915542229;1669.7078011347965;1162.9127815413708;1190.7380376317196;1215.7386161975041;1216.5194748074462;1219.8353222314875;5826.912596311059;5831.62094463867;5858.2677165354335;5859.824205273849;5863.589548731547;6193.964957171044;6219.782307619302;6222.284437289679;6235.688215589203;6247.656378899113;5687.630304030225;5788.565857560616;5827.621204621686;5855.590108692436;5865.384134665798;3261.445554255782;3271.987727547541;3280.114369120372;3287.474004159323;3291.374638818617;6269.498440124779;6467.159269295055;6467.320931302145;6475.061116138228;6540.4767618590395;6417.903731444032;6434.7104380303;6468.838279043061;6470.773522926675;6475.611463312216;6291.653959032297;6429.903551446826;6537.869242615121;6558.213014438298;6575.901361479581;6270;6282.900492413901;6285.59143655661;6300.9684951574945;6359.17330747;6005.429484198994;6219.129321894848;6264.655443950626;6275.623462592076;6275.838969790603;6352.587081839665;6371.069308118317;6385.808425747235;6394.2646595640235;6489.4808415325715;4239.766812825324;4244.893877653083;4246.8725938221905;4259.169461955013;4265.040365917768;6210.596311239755;6269.12232287471;6294.370562943852;6297.842553936188;6304.3380445054445;6085.6348619083;6120.479759220516;6122.632642041492;6125.234345706786;6136.189824002019;6000.279966404059;6013.639181649115;6112.960796664666;6129.141920131096;6143.815685529441;6059.484943779652;6069.150318955261;6072.271327440559;6078.787242446493;6126.549380247937;6025.909611355745;6109.75560977556;6115.388461154027;6148.231471066117;6158.692065396623;5886.087656413263;5899.203607512931;5907.5273978080795;5961.612495187799;5965 09:34:07 INFO - PID 17239 | |5;yui.html;851.3572252949023;851.3955091884761;871.8779370888072;876.3208513402117;879.028673315986;16745.162741862918;16830.747538786927;16986.745198822013;17367.13164341784;17369.826301736994;15789.60525986851;16729.581760455996;16818.83181168188;17218.569535761613;17232.56918577033;995.5221493683036;1016.8779746430439;1026.5021464589668;1028.8971102889725;1029.3978022856638;1029.0841151375282;1032.184574186392;1032.406366339354;1032.7314898126467;1032.9431881246528;1073.0717928991405;1077.6874706335154;1080.9135269178446;1081.3565928272665;1089.9727506812353;1065.8774240962234;1072.6299426697815;1073.2058276875096;1073.3291692692067;1082.1288862465713;785.5679376342988;785.6621652689341;786.2373497707272;787.6416230615056;789.6722860013145;15810.683786324209;15968.441104561285;17349.479515614552;17444;17510.387136450292;17127.74308385375;17141.400050998156;17942.820571794375;17966.730499042525;18087.457376278733;13934.233617151052;14166.716665666629;14406;14435.566932992027;15132.924335378362;3409.3863104641155;3511.0169152637304;5074.873128171788;5101.107306221396;5110.9744451277875;87881;88027.55986220091;89401.55299223462;90208;90294;14198.290085495684;14390.568282951528;14425.567232983027;14523.458394781082;14541.709165816625;26662.06682766094;26726.19821405361;26844.463110737677;26916;27463.450730985267;7695.730649427243;7783.0660320760735;7812.960935195288;7833.5691536966015;7834.764957051298;4028.617281358266;4125.29869922114;4143.709940304149;4144.3990621359735;4144.730592511477;4322.978385108117;4401.339799030107;4444.866654000385;4481.551844815493;4494.9325760113625;794.4399198564997;804.4610111225493;811.1037303779357;815.3925325632323;815.7797394703515;659.7262136213513;675.3482889012029;678.884589619756;687.2817905288985;689.5483458334699;11137.276077054965;12732.618021459371;12738.172018818746;12853.164544304776;12882.09825312219;721.1634503975364;725.8802297620833;736.716364199787;746.3208480283025;751.1887161865172;5184.922226166611;5338.733063346817;5357.705326207096;5415.891682166334;5473.397926228112;80734.596327018;83170.58414707887;84442.57778711067;85208.57395712982;87543.56228218818;74314.25685743181;75107.62446187733;75418.99999999891;75924;78542.6072869632;64047;65726.67136664285;65855.67072164608;66918.3308166912;67187.66406168036;10717.196210284104;11202.439878005904;11214.719632008939;11413.885861141282;11442.542298308308;35487.467687984186;35816.64183358133;36058.63941360553;37001.92210016775;37175.81412092922;41955.7902210487;42773.78613107039;42989.48610395988;43713.344299836164;44120;14402.495912643008;14464.710705786036;14666.85333146655;14729.779053313994;14917.328720207399;16206.594835128744;16533.173341332644;16646.334146634;16681.33274669048;16833.663326733153;14896.925515372353;14907.403703851724;14953;15067.4726384576;15252.466163684217;14085.15489070659;14092.577222682925;14116.153030818185;14213.857861421253;14371.353289101806;15535;17641.82358176402;18369.81630183681;18528.629427411644;18739.625207496047;12197.878021219674;12580.87419125797;12646.430910609228;12794.36028198605;13693.041487096207;14334.068285561405;14785.92607036958;14805.555833325017;15061.912307977058;15223.16272604973 09:34:07 INFO - PID 17239 | __end_tp_report 09:34:07 INFO - PID 17239 | __start_cc_report 09:34:07 INFO - PID 17239 | _x_x_mozilla_cycle_collect,2390 09:34:07 INFO - PID 17239 | __end_cc_report 09:34:07 INFO - PID 17239 | __startTimestamp1494606847546__endTimestamp 09:34:07 INFO - PID 17239 | 09:34:07 INFO - PID 17239 | ------- Summary: start ------- 09:34:07 INFO - PID 17239 | Number of tests: 6 09:34:07 INFO - PID 17239 | 09:34:07 INFO - PID 17239 | [#0] dojo.html Cycles:165 Average:5727.23 Median:6012.73 stddev:766.00 (12.7%) stddev-sans-first:745.03 09:34:07 INFO - PID 17239 | Values: 3335.9 3355.2 3374.4 3375.2 3376.8 5607.0 5725.1 5825.5 5890.9 5904.3 5715.7 5793.4 5794.3 5797.4 5808.4 5113.9 5134.5 5136.6 5166.4 5172.0 5204.9 5241.9 5268.8 5284.3 5337.7 5280.8 5292.6 5294.7 5299.0 5304.8 749.1 5082.6 5121.1 5236.5 5272.4 5197.9 5201.3 5202.2 5207.5 5211.5 5690.3 5799.1 5836.5 5846.8 5874.9 6043.2 6098.6 6120.1 6121.2 6121.9 6098.6 6103.1 6112.7 6113.4 6138.9 5734.2 5735.9 5737.4 5738.7 5744.2 6135.6 6178.3 6187.3 6197.9 6206.3 6013.8 6017.5 6018.0 6021.8 6027.5 5495.0 6044.0 6106.2 6115.7 6170.5 6193.0 6260.5 6293.7 6297.5 6305.4 6266.4 6279.7 6292.2 6294.6 6300.3 4649.8 4706.6 4758.1 4784.6 4814.7 4257.5 4266.3 4282.7 4297.0 4319.3 5915.7 5916.2 5919.3 5919.9 5971.5 6098.7 6138.3 6317.7 6366.0 6383.5 5877.1 5916.5 5967.9 5971.1 5980.8 5310.9 5341.9 5343.9 5345.1 5345.1 6206.8 6473.1 6477.6 6479.0 6499.7 6159.4 6198.5 6302.8 6314.1 6341.5 6506.9 6586.4 6586.6 6588.3 6588.4 6024.2 6241.7 6247.9 6249.5 6251.9 6119.9 6191.5 6194.9 6200.5 6205.5 6050.0 6113.1 6139.1 6140.5 6145.6 5858.2 6155.5 6170.5 6170.6 6176.1 6006.1 6012.7 6016.7 6019.0 6024.0 6058.0 6060.4 6070.4 6072.2 6073.2 5876.1 5881.7 5883.5 5884.4 5991.4 09:34:07 INFO - PID 17239 | 09:34:07 INFO - PID 17239 | [#1] ext.html Cycles:180 Average:37620.02 Median:33785.49 stddev:54921.87 (162.6%) stddev-sans-first:55006.34 09:34:07 INFO - PID 17239 | Values: 796.7 816.5 1138.1 1162.6 1165.7 34348.7 37950.8 38315.2 38407.6 38428.6 41031.4 41414.8 41429.8 41433.2 41448.8 5600.7 7254.4 7376.0 7376.8 7381.3 5730.2 7085.5 7129.2 7132.4 7346.5 7236.3 7250.8 7258.2 7395.7 7489.9 7207.1 7208.2 7215.0 7280.1 7332.9 7347.0 7368.6 7418.7 7419.0 7420.6 36575.6 36669.3 36898.3 36938.3 36954.8 3468.3 3513.1 3513.2 3513.8 3516.2 29021.6 29057.7 29094.9 29129.3 29134.3 3736.3 3741.9 3773.9 3783.4 3791.1 183056.0 184261.1 184317.0 184402.1 184422.0 39513.4 40228.8 48051.3 48311.8 48335.0 49636.8 50031.2 50336.0 50395.7 50557.2 14684.9 14813.4 14814.6 14819.5 14825.7 11765.1 11789.8 11795.9 11798.9 11804.9 13739.2 13774.4 13783.4 13866.9 13934.9 5152.8 5221.6 5241.5 5272.5 5290.8 1071.2 1090.2 1126.9 1128.0 1136.4 25861.4 26143.6 26315.0 26347.5 26355.9 1175.0 1186.4 1208.6 1236.2 1240.7 13317.2 13680.2 13735.6 13832.9 13858.4 236100.0 236419.0 237066.0 237379.0 237438.6 51952.7 52081.0 52181.0 52276.7 52323.0 1501.7 1511.6 1528.7 1549.7 1556.9 24619.4 25209.1 25295.9 25705.0 25802.8 38605.2 38729.4 38741.2 38764.4 38837.2 210843.0 210925.0 211095.0 211410.9 211612.0 29915.3 30416.7 33498.7 34072.3 34342.7 39930.0 40487.8 41286.0 41666.6 41730.6 30983.2 31001.4 31145.0 31366.8 32626.8 31072.7 31091.0 31155.0 32317.2 32584.0 36521.8 36559.5 36568.5 36600.3 38536.2 32159.5 32612.2 32726.3 32874.5 33332.0 24550.3 26628.7 26638.9 26741.7 27332.9 09:34:07 INFO - PID 17239 | 09:34:07 INFO - PID 17239 | [#2] jquery.html Cycles:180 Average:29532.98 Median:2140.48 stddev:73777.30 (3446.8%) stddev-sans-first:73951.90 09:34:07 INFO - PID 17239 | Values: 428.6 432.9 443.5 460.7 471.2 18448.9 19536.7 20127.4 20255.3 20293.4 20665.1 20867.5 20945.6 20946.4 20963.6 5489.8 5735.6 5740.5 5746.5 5747.9 5424.2 5598.4 5629.9 5677.4 5690.6 5605.8 5638.5 5641.2 5643.9 5647.3 5433.5 5559.3 5597.6 5600.4 5622.6 5074.2 5104.3 5111.8 5112.1 5112.8 25276.4 26211.9 26258.5 26368.6 27312.0 2093.8 2129.2 2130.9 2138.5 2142.5 16898.6 17412.4 17600.7 17606.6 17672.0 2137.4 2227.7 2241.4 2271.4 2293.4 109102.9 113307.0 113579.4 113880.4 116517.0 12377.8 12384.4 12518.6 12595.0 12805.2 18084.9 18102.4 18104.2 18107.7 18170.2 8509.2 8673.9 8709.0 8792.9 8874.0 7865.5 7987.4 7989.8 8126.6 8157.3 7878.1 8136.2 8150.9 8180.0 8292.3 2230.7 2253.3 2260.6 2265.9 2403.3 1028.8 1066.8 1068.9 1070.2 1070.8 19339.6 20464.5 20571.2 20755.9 20881.6 1059.2 1064.7 1065.1 1066.8 1074.8 10771.5 11050.9 11051.9 11153.9 11228.2 430230.0 434104.0 443021.0 448638.0 449034.0 91187.5 92198.0 95466.0 97513.0 98097.0 1147.0 1186.4 1204.1 1206.6 1214.6 16601.5 17186.1 17456.0 17566.9 17788.9 44024.6 44230.8 44644.1 45313.3 45370.0 56830.7 56848.4 57093.0 57596.4 57820.4 9035.0 9092.5 9387.7 9619.7 9779.5 14188.7 15097.4 15138.8 15699.0 15736.8 12849.2 12947.3 13118.1 13542.4 13775.4 11365.3 11461.0 11868.3 12186.9 12563.9 13109.7 13335.8 13336.5 13734.9 13756.2 9610.3 9638.0 9638.6 9893.7 10071.1 12181.4 12457.4 12500.6 12681.0 12743.9 09:34:07 INFO - PID 17239 | 09:34:07 INFO - PID 17239 | [#3] mootools.html Cycles:180 Average:7414.05 Median:4016.38 stddev:4773.16 (118.8%) stddev-sans-first:4763.81 09:34:07 INFO - PID 17239 | Values: 1213.8 1392.2 1431.7 1443.3 1448.3 9939.2 11418.4 11990.8 12001.9 12016.8 11600.0 11694.6 11709.1 11727.8 11736.2 2013.3 2100.0 2415.5 2459.0 2460.1 2355.5 2442.0 2448.8 2449.2 2450.0 2407.3 2416.5 2426.2 2426.6 2434.1 2392.6 2401.4 2407.2 2410.0 2410.4 2289.0 2291.3 2299.2 2301.8 2301.9 11359.1 11376.1 11443.2 11452.1 11454.8 6282.2 6531.8 6533.9 6643.5 6653.3 3709.5 3925.7 4107.1 4126.6 4134.6 1324.6 1325.3 1380.9 1387.3 1390.8 18012.5 18016.1 18039.1 18049.5 18074.5 5621.4 5667.3 5764.3 5771.8 5825.0 14623.7 14629.3 14648.9 14655.6 14655.8 6590.6 6630.9 6640.9 6641.5 6669.5 5978.7 5998.9 6040.7 6108.0 6159.9 4461.9 4527.3 4598.7 4634.3 4656.8 1384.2 1390.2 1407.6 1417.5 1421.9 861.3 878.2 891.9 899.1 922.6 7112.8 7160.7 7489.2 7766.1 7941.2 966.6 983.7 985.6 991.5 996.4 4507.2 4665.8 4715.5 4726.4 4853.4 15065.6 15321.1 15384.4 15551.8 15883.0 15046.5 15372.7 15448.5 15459.8 15541.5 9540.8 9666.4 9799.1 9818.5 9841.6 6888.3 7240.7 7596.9 7637.7 7669.8 13541.9 13761.2 13861.4 13877.2 13941.7 13612.9 14024.1 14035.4 14123.4 14223.6 9442.7 9673.2 9695.0 9840.0 9842.6 11062.3 11135.6 11168.8 11191.7 11210.5 8845.4 8983.5 9004.0 9243.2 9269.6 8820.0 8935.0 9001.9 9041.2 9056.9 8406.5 8590.6 8595.5 8601.7 8609.0 6299.0 6351.7 6352.3 6409.7 6581.5 6295.9 6403.7 6410.4 6430.7 6706.0 09:34:07 INFO - PID 17239 | 09:34:07 INFO - PID 17239 | [#4] prototype.html Cycles:180 Average:5162.94 Median:5822.61 stddev:1479.58 (25.4%) stddev-sans-first:1439.69 09:34:07 INFO - PID 17239 | Values: 388.7 409.5 409.8 412.6 412.9 5529.4 5731.4 5782.8 5837.5 5844.8 5544.2 5681.0 5731.6 5735.0 5742.0 4553.3 4556.3 4563.9 4571.8 4577.6 4431.5 4521.5 4550.7 4552.5 4562.5 4489.3 4513.3 4516.1 4517.4 4521.1 4528.6 4529.3 4532.3 4534.4 4538.8 4044.3 4078.5 4089.9 4113.8 4117.9 5598.8 5599.2 5601.7 5612.8 5617.4 5288.4 5330.2 5336.7 5357.1 5372.8 6010.3 6047.9 6071.2 6086.8 6092.5 4575.1 4578.0 4594.0 4596.4 4600.3 6138.6 6212.8 6273.9 6274.1 6299.6 4689.8 4715.6 4727.3 4751.1 4751.2 4776.3 4797.5 4798.8 4800.8 4802.5 5253.2 5291.0 5324.4 5331.9 5344.5 5818.3 5899.9 6087.7 6119.7 6142.5 1597.8 1646.6 1661.3 1662.2 1669.7 1162.9 1190.7 1215.7 1216.5 1219.8 5826.9 5831.6 5858.3 5859.8 5863.6 6194.0 6219.8 6222.3 6235.7 6247.7 5687.6 5788.6 5827.6 5855.6 5865.4 3261.4 3272.0 3280.1 3287.5 3291.4 6269.5 6467.2 6467.3 6475.1 6540.5 6417.9 6434.7 6468.8 6470.8 6475.6 6291.7 6429.9 6537.9 6558.2 6575.9 6270.0 6282.9 6285.6 6301.0 6359.2 6005.4 6219.1 6264.7 6275.6 6275.8 6352.6 6371.1 6385.8 6394.3 6489.5 4239.8 4244.9 4246.9 4259.2 4265.0 6210.6 6269.1 6294.4 6297.8 6304.3 6085.6 6120.5 6122.6 6125.2 6136.2 6000.3 6013.6 6113.0 6129.1 6143.8 6059.5 6069.2 6072.3 6078.8 6126.5 6025.9 6109.8 6115.4 6148.2 6158.7 5886.1 5899.2 5907.5 5961.6 5965.0 09:34:07 INFO - PID 17239 | 09:34:07 INFO - PID 17239 | [#5] yui.html Cycles:180 Average:18953.55 Median:18228.64 stddev:23494.60 (128.9%) stddev-sans-first:23521.18 09:34:07 INFO - PID 17239 | Values: 851.4 851.4 871.9 876.3 879.0 16745.2 16830.7 16986.7 17367.1 17369.8 15789.6 16729.6 16818.8 17218.6 17232.6 995.5 1016.9 1026.5 1028.9 1029.4 1029.1 1032.2 1032.4 1032.7 1032.9 1073.1 1077.7 1080.9 1081.4 1090.0 1065.9 1072.6 1073.2 1073.3 1082.1 785.6 785.7 786.2 787.6 789.7 15810.7 15968.4 17349.5 17444.0 17510.4 17127.7 17141.4 17942.8 17966.7 18087.5 13934.2 14166.7 14406.0 14435.6 15132.9 3409.4 3511.0 5074.9 5101.1 5111.0 87881.0 88027.6 89401.6 90208.0 90294.0 14198.3 14390.6 14425.6 14523.5 14541.7 26662.1 26726.2 26844.5 26916.0 27463.5 7695.7 7783.1 7813.0 7833.6 7834.8 4028.6 4125.3 4143.7 4144.4 4144.7 4323.0 4401.3 4444.9 4481.6 4494.9 794.4 804.5 811.1 815.4 815.8 659.7 675.3 678.9 687.3 689.5 11137.3 12732.6 12738.2 12853.2 12882.1 721.2 725.9 736.7 746.3 751.2 5184.9 5338.7 5357.7 5415.9 5473.4 80734.6 83170.6 84442.6 85208.6 87543.6 74314.3 75107.6 75419.0 75924.0 78542.6 64047.0 65726.7 65855.7 66918.3 67187.7 10717.2 11202.4 11214.7 11413.9 11442.5 35487.5 35816.6 36058.6 37001.9 37175.8 41955.8 42773.8 42989.5 43713.3 44120.0 14402.5 14464.7 14666.9 14729.8 14917.3 16206.6 16533.2 16646.3 16681.3 16833.7 14896.9 14907.4 14953.0 15067.5 15252.5 14085.2 14092.6 14116.2 14213.9 14371.4 15535.0 17641.8 18369.8 18528.6 18739.6 12197.9 12580.9 12646.4 12794.4 13693.0 14334.1 14785.9 14805.6 15061.9 15223.2 09:34:07 INFO - PID 17239 | -------- Summary: end -------- 09:34:07 INFO - PID 17239 | 09:34:08 INFO - TEST-INFO | 17239: exit 0 09:34:08 INFO - TEST-OK | dromaeo_css | took 1115164ms 09:34:08 INFO - TEST-START | kraken 09:34:08 INFO - operating with platform_type : linux_ 09:34:08 INFO - Initialising browser for kraken test... 09:34:08 INFO - TEST-INFO | started process 17579 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZ3BZWM/profile http://localhost:35208/getInfo.html) 09:34:11 INFO - PID 17579 | __metrics Screen width/height:1600/1200 09:34:11 INFO - PID 17579 | colorDepth:24 09:34:11 INFO - PID 17579 | Browser inner width/height: 1024/695 09:34:11 INFO - PID 17579 | __metrics 09:34:12 INFO - PID 17579 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:34:12 INFO - PID 17579 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:34:13 INFO - TEST-INFO | 17579: exit 0 09:34:13 INFO - Browser initialized. 09:34:13 INFO - Running cycle 1/1 for kraken test... 09:34:13 INFO - TEST-INFO | started process 17678 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZ3BZWM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:34:25 INFO - PID 17678 | RSS: Main: 148967424 09:34:25 INFO - PID 17678 | 09:34:27 INFO - PID 17678 | Cycle 1(1): loaded http://localhost:35208/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35208/tests/kraken/driver.html?testName=audio-beat-detection) 09:34:28 INFO - PID 17678 | RSS: Main: 217079808 09:34:28 INFO - PID 17678 | 09:34:31 INFO - PID 17678 | Cycle 1(1): loaded http://localhost:35208/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35208/tests/kraken/driver.html?testName=audio-dft) 09:34:31 INFO - PID 17678 | RSS: Main: 179482624 09:34:31 INFO - PID 17678 | 09:34:34 INFO - PID 17678 | Cycle 1(1): loaded http://localhost:35208/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35208/tests/kraken/driver.html?testName=audio-fft) 09:34:35 INFO - PID 17678 | RSS: Main: 261234688 09:34:35 INFO - PID 17678 | 09:34:37 INFO - PID 17678 | Cycle 1(1): loaded http://localhost:35208/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35208/tests/kraken/driver.html?testName=audio-oscillator) 09:34:37 INFO - PID 17678 | RSS: Main: 173101056 09:34:37 INFO - PID 17678 | 09:34:39 INFO - PID 17678 | Cycle 1(1): loaded http://localhost:35208/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35208/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:34:40 INFO - PID 17678 | RSS: Main: 175063040 09:34:40 INFO - PID 17678 | 09:34:43 INFO - PID 17678 | Cycle 1(1): loaded http://localhost:35208/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35208/tests/kraken/driver.html?testName=imaging-darkroom) 09:34:44 INFO - PID 17678 | RSS: Main: 235147264 09:34:44 INFO - PID 17678 | 09:34:47 INFO - PID 17678 | Cycle 1(1): loaded http://localhost:35208/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35208/tests/kraken/driver.html?testName=imaging-desaturate) 09:34:48 INFO - PID 17678 | RSS: Main: 259702784 09:34:48 INFO - PID 17678 | 09:34:50 INFO - PID 17678 | Cycle 1(1): loaded http://localhost:35208/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35208/tests/kraken/driver.html?testName=json-parse-financial) 09:34:51 INFO - PID 17678 | RSS: Main: 279339008 09:34:51 INFO - PID 17678 | 09:34:52 INFO - PID 17678 | Cycle 1(1): loaded http://localhost:35208/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35208/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:34:53 INFO - PID 17678 | RSS: Main: 198889472 09:34:53 INFO - PID 17678 | 09:34:55 INFO - PID 17678 | Cycle 1(1): loaded http://localhost:35208/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35208/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:34:55 INFO - PID 17678 | RSS: Main: 203468800 09:34:55 INFO - PID 17678 | 09:34:57 INFO - PID 17678 | Cycle 1(1): loaded http://localhost:35208/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35208/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:34:58 INFO - PID 17678 | RSS: Main: 213245952 09:34:58 INFO - PID 17678 | 09:35:00 INFO - PID 17678 | Cycle 1(1): loaded http://localhost:35208/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35208/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:35:01 INFO - PID 17678 | RSS: Main: 222339072 09:35:01 INFO - PID 17678 | 09:35:04 INFO - PID 17678 | Cycle 1(1): loaded http://localhost:35208/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35208/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:35:05 INFO - PID 17678 | RSS: Main: 217133056 09:35:05 INFO - PID 17678 | 09:35:06 INFO - PID 17678 | Cycle 1(1): loaded http://localhost:35208/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35208/tests/kraken/driver.html?testName=ai-astar) 09:35:06 INFO - PID 17678 | RSS: Main: 267849728 09:35:06 INFO - PID 17678 | 09:35:06 INFO - PID 17678 | __start_tp_report 09:35:06 INFO - PID 17678 | _x_x_mozilla_page_load 09:35:06 INFO - PID 17678 | _x_x_mozilla_page_load_details 09:35:06 INFO - PID 17678 | |i|pagename|runs| 09:35:06 INFO - PID 17678 | |0;ai-astar;104;112;110;108;110;110;111;109;109;110 09:35:06 INFO - PID 17678 | |1;audio-beat-detection;148;117;119;172;117;117;118;116;147;117 09:35:06 INFO - PID 17678 | |2;audio-dft;161;161;161;162;162;160;162;161;160;163 09:35:06 INFO - PID 17678 | |3;audio-fft;73;73;74;113;74;74;74;73;97;76 09:35:06 INFO - PID 17678 | |4;audio-oscillator;82;82;106;82;84;82;95;82;82;107 09:35:06 INFO - PID 17678 | |5;imaging-gaussian-blur;113;113;113;113;113;113;113;113;112;113 09:35:06 INFO - PID 17678 | |6;imaging-darkroom;147;147;146;147;147;147;146;147;147;146 09:35:06 INFO - PID 17678 | |7;imaging-desaturate;94;96;93;94;94;94;94;94;95;94 09:35:06 INFO - PID 17678 | |8;json-parse-financial;50;50;49;48;49;53;48;51;50;49 09:35:06 INFO - PID 17678 | |9;json-stringify-tinderbox;54;54;54;55;54;53;55;54;55;55 09:35:06 INFO - PID 17678 | |10;stanford-crypto-aes;86;78;78;78;79;79;85;80;79;79 09:35:06 INFO - PID 17678 | |11;stanford-crypto-ccm;126;126;125;131;122;123;121;122;170;122 09:35:06 INFO - PID 17678 | |12;stanford-crypto-pbkdf2;186;179;180;177;178;179;177;170;178;180 09:35:06 INFO - PID 17678 | |13;stanford-crypto-sha256-iterative;65;66;64;68;65;64;63;64;62;63 09:35:06 INFO - PID 17678 | __end_tp_report 09:35:06 INFO - PID 17678 | __start_cc_report 09:35:06 INFO - PID 17678 | _x_x_mozilla_cycle_collect,1698 09:35:06 INFO - PID 17678 | __end_cc_report 09:35:06 INFO - PID 17678 | __startTimestamp1494606906978__endTimestamp 09:35:06 INFO - PID 17678 | 09:35:06 INFO - PID 17678 | ------- Summary: start ------- 09:35:06 INFO - PID 17678 | Number of tests: 14 09:35:06 INFO - PID 17678 | 09:35:06 INFO - PID 17678 | [#0] ai-astar Cycles:10 Average:109.30 Median:110.00 stddev:2.16 (2.0%) stddev-sans-first:1.17 09:35:06 INFO - PID 17678 | Values: 104.0 112.0 110.0 108.0 110.0 110.0 111.0 109.0 109.0 110.0 09:35:06 INFO - PID 17678 | 09:35:06 INFO - PID 17678 | [#1] audio-beat-detection Cycles:10 Average:128.80 Median:118.50 stddev:19.72 (16.6%) stddev-sans-first:19.65 09:35:06 INFO - PID 17678 | Values: 148.0 117.0 119.0 172.0 117.0 117.0 118.0 116.0 147.0 117.0 09:35:06 INFO - PID 17678 | 09:35:06 INFO - PID 17678 | [#2] audio-dft Cycles:10 Average:161.30 Median:161.50 stddev:0.95 (0.6%) stddev-sans-first:1.00 09:35:06 INFO - PID 17678 | Values: 161.0 161.0 161.0 162.0 162.0 160.0 162.0 161.0 160.0 163.0 09:35:06 INFO - PID 17678 | 09:35:06 INFO - PID 17678 | [#3] audio-fft Cycles:10 Average:80.10 Median:74.00 stddev:13.68 (18.5%) stddev-sans-first:14.27 09:35:06 INFO - PID 17678 | Values: 73.0 73.0 74.0 113.0 74.0 74.0 74.0 73.0 97.0 76.0 09:35:06 INFO - PID 17678 | 09:35:06 INFO - PID 17678 | [#4] audio-oscillator Cycles:10 Average:88.40 Median:82.00 stddev:10.35 (12.6%) stddev-sans-first:10.72 09:35:06 INFO - PID 17678 | Values: 82.0 82.0 106.0 82.0 84.0 82.0 95.0 82.0 82.0 107.0 09:35:06 INFO - PID 17678 | 09:35:06 INFO - PID 17678 | [#5] imaging-gaussian-blur Cycles:10 Average:112.90 Median:113.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 09:35:06 INFO - PID 17678 | Values: 113.0 113.0 113.0 113.0 113.0 113.0 113.0 113.0 112.0 113.0 09:35:06 INFO - PID 17678 | 09:35:06 INFO - PID 17678 | [#6] imaging-darkroom Cycles:10 Average:146.70 Median:147.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 09:35:06 INFO - PID 17678 | Values: 147.0 147.0 146.0 147.0 147.0 147.0 146.0 147.0 147.0 146.0 09:35:06 INFO - PID 17678 | 09:35:06 INFO - PID 17678 | [#7] imaging-desaturate Cycles:10 Average:94.20 Median:94.00 stddev:0.79 (0.8%) stddev-sans-first:0.83 09:35:06 INFO - PID 17678 | Values: 94.0 96.0 93.0 94.0 94.0 94.0 94.0 94.0 95.0 94.0 09:35:06 INFO - PID 17678 | 09:35:06 INFO - PID 17678 | [#8] json-parse-financial Cycles:10 Average:49.70 Median:50.00 stddev:1.49 (3.0%) stddev-sans-first:1.58 09:35:06 INFO - PID 17678 | Values: 50.0 50.0 49.0 48.0 49.0 53.0 48.0 51.0 50.0 49.0 09:35:06 INFO - PID 17678 | 09:35:06 INFO - PID 17678 | [#9] json-stringify-tinderbox Cycles:10 Average:54.30 Median:54.50 stddev:0.67 (1.2%) stddev-sans-first:0.71 09:35:06 INFO - PID 17678 | Values: 54.0 54.0 54.0 55.0 54.0 53.0 55.0 54.0 55.0 55.0 09:35:06 INFO - PID 17678 | 09:35:06 INFO - PID 17678 | [#10] stanford-crypto-aes Cycles:10 Average:80.10 Median:79.00 stddev:2.92 (3.7%) stddev-sans-first:2.19 09:35:06 INFO - PID 17678 | Values: 86.0 78.0 78.0 78.0 79.0 79.0 85.0 80.0 79.0 79.0 09:35:06 INFO - PID 17678 | 09:35:06 INFO - PID 17678 | [#11] stanford-crypto-ccm Cycles:10 Average:128.80 Median:125.50 stddev:14.78 (11.8%) stddev-sans-first:15.64 09:35:06 INFO - PID 17678 | Values: 126.0 126.0 125.0 131.0 122.0 123.0 121.0 122.0 170.0 122.0 09:35:06 INFO - PID 17678 | 09:35:06 INFO - PID 17678 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.40 Median:179.00 stddev:3.92 (2.2%) stddev-sans-first:3.05 09:35:06 INFO - PID 17678 | Values: 186.0 179.0 180.0 177.0 178.0 179.0 177.0 170.0 178.0 180.0 09:35:06 INFO - PID 17678 | 09:35:06 INFO - PID 17678 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.40 Median:64.50 stddev:1.71 (2.7%) stddev-sans-first:1.80 09:35:06 INFO - PID 17678 | Values: 65.0 66.0 64.0 68.0 65.0 64.0 63.0 64.0 62.0 63.0 09:35:06 INFO - PID 17678 | -------- Summary: end -------- 09:35:06 INFO - PID 17678 | 09:35:08 INFO - TEST-INFO | 17678: exit 0 09:35:08 INFO - TEST-OK | kraken | took 59937ms 09:35:08 INFO - SUITE-END | took 1175s 09:35:08 INFO - Completed test suite (00:19:35) 09:35:08 INFO - javascript benchmark 09:35:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7703.645099632555, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3335.8832440864576, 3355.1779813945586, 3374.4094783412897, 3375.240570871554, 3376.79739215647, 5607.046802043652, 5725.055365864628, 5825.533957283418, 5890.88218235635, 5904.291485021793, 5715.714214289279, 5793.391693872146, 5794.333651630064, 5797.449242321973, 5808.361080281176, 5113.897722045566, 5134.512221338967, 5136.614753893455, 5166.354205724285, 5172.017316709813, 5204.947950520503, 5241.8951620967555, 5268.841934741964, 5284.339457567804, 5337.733113344337, 5280.762365693553, 5292.629515933886, 5294.682319060849, 5298.993191293642, 5304.814331498398, 749.0935502663048, 5082.6188035897085, 5121.103806833789, 5236.528712415901, 5272.420033796279, 5197.870053248661, 5201.349831271091, 5202.245674377232, 5207.479252074762, 5211.452797456251, 5690.2887139107615, 5799.101139323369, 5836.533077353844, 5846.824595262106, 5874.882502349929, 6043.1841701370195, 6098.634081955097, 6120.051392034242, 6121.173641558377, 6121.877562448726, 6098.595953128956, 6103.1394811735445, 6112.685862051835, 6113.415495409501, 6138.907916381258, 5734.16854556087, 5735.8566035849435, 5737.3688894221605, 5738.655680659173, 5744.195812586241, 6135.569531795964, 6178.258608966953, 6187.319394866561, 6197.907031394533, 6206.348333425016, 6013.789517366871, 6017.548683848729, 6018.037114061729, 6021.819345419644, 6027.547933904974, 5494.972525137428, 6043.969780151159, 6106.175666285084, 6115.663638499836, 6170.475509581646, 6193.0, 6260.499160067184, 6293.653849038347, 6297.4647154992335, 6305.4009869062365, 6266.373362663789, 6279.717412716439, 6292.1505596743855, 6294.590851594723, 6300.2754683212015, 4649.767511624405, 4706.623470122382, 4758.07217592573, 4784.617230621542, 4814.711117332901, 4257.510386305553, 4266.274733295348, 4282.700210985264, 4296.957030429656, 4319.308910574356, 5915.674637894957, 5916.171735956969, 5919.260092488439, 5919.881602367928, 5971.5222782177325, 6098.717026081328, 6138.339901207151, 6317.684115794192, 6366.0, 6383.489320854321, 5877.118432235114, 5916.467517923407, 5967.910481342783, 5971.134185543072, 5980.820575382745, 5310.867228319245, 5341.946580534222, 5343.919841202385, 5345.118055520796, 5345.1447768356875, 6206.813795586139, 6473.061406096184, 6477.611343319416, 6478.967605161944, 6499.675016249169, 6159.414855588712, 6198.504119670506, 6302.810915672537, 6314.116023756769, 6341.524385671185, 6506.934930650822, 6586.407223349922, 6586.571872828251, 6588.308227636221, 6588.374104460068, 6024.216851809237, 6241.687915604292, 6247.875042499034, 6249.500039996698, 6251.937480625317, 6119.929102025315, 6191.473724733268, 6194.907076393768, 6200.503959683124, 6205.47253483459, 6050.001749711424, 6113.1135985281, 6139.093519790236, 6140.534923917806, 6145.631262124287, 5858.179854820243, 6155.47678447337, 6170.475509581735, 6170.629762214281, 6176.073588961692, 6006.099085137264, 6012.729427175875, 6016.7292471838, 6018.969905150446, 6024.036154215304, 6058.0, 6060.393960603904, 6070.362611925861, 6072.180255665428, 6073.180120683828, 5876.147958546072, 5881.676507791942, 5883.529317654492, 5884.382139875252, 5991.370906054802], "value": 5677.138091337084, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [796.7251298302085, 816.5468165168332, 1138.1350173867859, 1162.5698491558126, 1165.6794381545074, 34348.65651343486, 37950.81024594876, 38315.233695326075, 38407.615923840756, 38428.61571384289, 41031.384529232084, 41414.7929260354, 41429.79285103578, 41433.17133657325, 41448.79275603625, 5600.719964001804, 7254.383377412913, 7375.963120184391, 7376.7786966391095, 7381.29877661624, 5730.197772311859, 7085.468589855782, 7129.215786263507, 7132.429405647562, 7346.522476039067, 7236.348728614424, 7250.782476525713, 7258.201597824262, 7395.741149059784, 7489.9251007489775, 7207.13514378278, 7208.207097219301, 7215.0, 7280.053593032898, 7332.8900066499045, 7347.0, 7368.557886526825, 7418.740344087985, 7418.96290518544, 7420.5547667140145, 36575.63424365749, 36669.266614667824, 36898.26203475942, 36938.261234775426, 36954.815225923696, 3468.3410152071024, 3513.139280876176, 3513.2270900401872, 3513.8243087845503, 3516.226430185355, 29021.56467652987, 29057.709422905922, 29094.85452572745, 29129.271768205752, 29134.27164320888, 3736.327461057009, 3741.8877433677035, 3773.9433908491396, 3783.4135708964877, 3791.146991926822, 183056.0, 184261.07869460701, 184317.0, 184402.07798961052, 184422.0, 39513.40729889054, 40228.79885600612, 48051.27923081157, 48311.75844120827, 48335.0, 49636.75181624141, 50031.24953125706, 50336.0, 50395.74802125966, 50557.24164137541, 14684.85315146835, 14813.407463701331, 14814.555563333117, 14819.481318153812, 14825.70348593022, 11765.11761617882, 11789.762074982174, 11795.879391457798, 11798.88201117995, 11804.940975295069, 13739.24434156131, 13774.449022039207, 13783.448662053406, 13866.861331386759, 13934.930325348309, 5152.845414637567, 5221.582273418117, 5241.528262456397, 5272.525472707474, 5290.814821481306, 1071.2394200118003, 1090.2095980414135, 1126.8985791278824, 1128.0242590159617, 1136.4090672850236, 25861.35346616312, 26143.60784588233, 26315.0, 26347.473050538883, 26355.868220658773, 1175.0071189844568, 1186.4008675618757, 1208.5830388516124, 1236.2273579013117, 1240.7394447166016, 13317.200967941955, 13680.247586382844, 13735.587932362045, 13832.861671383158, 13858.376373063236, 236100.0, 236419.0, 237066.0, 237379.0, 237438.56649317738, 51952.740236298574, 52081.0, 52181.0, 52276.73861630668, 52323.0, 1501.677139415031, 1511.5767585076196, 1528.7171873203492, 1549.7133030389414, 1556.9143697096767, 24619.38451538654, 25209.11768088108, 25295.873520632278, 25705.0, 25802.774653168355, 38605.22789544137, 38729.41905871358, 38741.22517549577, 38764.41853372258, 38837.2232555353, 210843.0, 210925.0, 211095.0, 211410.9429452843, 211612.0, 29915.252118697208, 30416.695833041387, 33498.66501334956, 34072.31855362929, 34342.656573433946, 39930.0, 40487.797561012005, 41286.0, 41666.58333416748, 41730.58269417267, 30983.225419364695, 31001.379972399973, 31145.0, 31366.843165784026, 32626.83686581552, 31072.68927310698, 31091.0, 31155.0, 32317.192070198435, 32584.0, 36521.817390912875, 36559.45160822643, 36568.45147322739, 36600.26799464049, 38536.229275414895, 32159.51760723638, 32612.184695382806, 32726.34547309088, 32874.50688239631, 33332.0, 24550.263492095266, 26628.7337126634, 26638.866805665846, 26741.732582673925, 27332.86333568399], "value": 16945.582985540696, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [428.59497774602994, 432.89826890680706, 443.51656694203314, 460.6660171375753, 471.18955396717644, 18448.907755461238, 19536.706949395753, 20127.396178114643, 20255.291064812736, 20293.391198264075, 20665.070071846767, 20867.478313042182, 20945.581088378225, 20946.371608851758, 20963.580728385423, 5489.835304940858, 5735.627184233012, 5740.483356497914, 5746.454086861741, 5747.856303592423, 5424.213489044087, 5598.440155984389, 5629.887402251952, 5677.432256774331, 5690.57320700947, 5605.803796867111, 5638.492535671789, 5641.210230567704, 5643.887122257553, 5647.322321321427, 5433.483819037185, 5559.305086864142, 5597.63615365, 5600.439956004367, 5622.550195984311, 5074.213496907985, 5104.310918026056, 5111.821086261963, 5112.130937740593, 5112.769925353368, 25276.368090797692, 26211.903047756325, 26258.474830503474, 26368.60447093295, 27312.0, 2093.822025127866, 2129.2228336657163, 2130.946726331839, 2138.4760733620205, 2142.475093602062, 16898.577535561588, 17412.390566330243, 17600.66788914235, 17606.559836004075, 17672.0, 2137.422895818136, 2227.7103976483118, 2241.4396400899777, 2271.4207876991263, 2293.3578597992587, 109102.90897091007, 113307.0, 113579.43210283978, 113880.43059784731, 116516.99999999916, 12377.752444951231, 12384.442700078518, 12518.624441266777, 12595.0, 12805.231686098863, 18084.9095754523, 18102.3664171756, 18104.185311661007, 18107.72838407425, 18170.182341794385, 8509.234168924826, 8673.869891951626, 8708.956455217683, 8792.8681069784, 8874.0, 7865.488743231672, 7987.400944929153, 7989.760307190793, 8126.593670316579, 8157.3474122071275, 7878.1334053254095, 8136.227058429558, 8150.858879756839, 8180.018397792184, 8292.253697167283, 2230.732312122523, 2253.2789507357816, 2260.6269965455845, 2265.920692775745, 2403.26700356393, 1028.8096702110133, 1066.7813098314828, 1068.8877667844765, 1070.240129508058, 1070.7697844963213, 19339.613207735765, 20464.488387790127, 20571.177152914017, 20755.8962205188, 20881.58236835255, 1059.1579694143174, 1064.7125276175389, 1065.1478816946412, 1066.8079745645703, 1074.8334008228737, 10771.515281812337, 11050.944745276383, 11051.944740276247, 11153.944230278797, 11228.157888158257, 430230.0, 434104.0, 443021.0, 448638.0, 449034.0, 91187.54406228059, 92198.0, 95466.0, 97513.0, 98097.0, 1147.0231790291414, 1186.3890096600257, 1204.0608325506118, 1206.595790410205, 1214.5506162719673, 16601.50195494137, 17186.140692965553, 17456.0, 17566.91216543909, 17788.91105544464, 44024.55975440333, 44230.77884610556, 44644.10711785681, 45313.32030019618, 45370.0, 56830.715846420506, 56848.431515684315, 57093.0, 57596.424035759104, 57820.421795781505, 9034.954825225832, 9092.454452732858, 9387.671431499864, 9619.711408657751, 9779.511024448606, 14188.716225675222, 15097.39610415571, 15138.772918406454, 15699.0, 15736.763948540553, 12849.164804287691, 12947.287899165636, 13118.08173427889, 13542.390592423562, 13775.38010789537, 11365.31808091517, 11461.0, 11868.347240901834, 12186.87813121893, 12563.937180314406, 13109.737805244033, 13335.799963000369, 13336.466541338626, 13734.93132534331, 13756.243406612732, 9610.327277090537, 9638.049655997664, 9638.590352411897, 9893.65372211969, 10071.143952763805, 12181.390930453264, 12457.439415226518, 12500.562480313509, 12681.048921330752, 12743.93628031854], "value": 10099.629563088038, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1213.8118591618302, 1392.1647011792923, 1431.7422863884503, 1443.2639353929487, 1448.3482432905196, 9939.155171810396, 11418.42907854607, 11990.7601847963, 12001.879981200185, 12016.7596648067, 11600.0, 11694.649160525198, 11709.063274938006, 11727.824082638746, 11736.237144585595, 2013.2550956146174, 2099.9895000525053, 2415.5048215115903, 2459.0409740201335, 2460.1020627471025, 2355.49356888269, 2442.0353960185716, 2448.8285819992607, 2449.2407353720314, 2450.019992003195, 2407.337982054931, 2416.4562973331035, 2426.2478631624163, 2426.5874801283826, 2434.087217293515, 2392.6411038344213, 2401.4356626192916, 2407.16952651335, 2409.9518009639796, 2410.397400649829, 2289.0, 2291.2553420138356, 2299.2067736630834, 2301.8043466305385, 2301.873396963166, 11359.091272698164, 11376.146788990776, 11443.198976071679, 11452.141089418326, 11454.770904581861, 6282.151909492251, 6531.771388001444, 6533.93466065338, 6643.468522518187, 6653.301403352675, 3709.536307961505, 3925.685945124412, 4107.075907920724, 4126.607972242632, 4134.565870583606, 1324.569514907659, 1325.3307079924673, 1380.9171449713049, 1387.2508845223567, 1390.8025319994385, 18012.54968625782, 18016.099195040195, 18039.098045097824, 18049.54876128094, 18074.457766267034, 5621.353544342372, 5667.3482549507335, 5764.308283006066, 5771.769129234867, 5824.970875145682, 14623.707525849424, 14629.341679624442, 14648.926755366156, 14655.560333190022, 14655.780163297559, 6590.604563726191, 6630.900536491957, 6640.867182656417, 6641.501887358468, 6669.533132680763, 5978.701064946736, 5998.850028749229, 6040.6979651018155, 6107.969460152671, 6159.938400616026, 4461.866144015685, 4527.253003254426, 4598.747068911242, 4634.304854271886, 4656.837010704609, 1384.1694983010113, 1390.1728471559406, 1407.6410515318528, 1417.482618844135, 1421.8933579981542, 861.2765277167084, 878.1701292278735, 891.8662200669952, 899.0604817965242, 922.5940586142076, 7112.822179445555, 7160.706075205464, 7489.176190619137, 7766.106897706836, 7941.166177551274, 966.6230170233614, 983.6606370802061, 985.5909797434128, 991.4695637833869, 996.3922007575374, 4507.211238033331, 4665.766711664403, 4715.504871988392, 4726.361941137971, 4853.417589889236, 15065.623359416102, 15321.080735155703, 15384.38462461489, 15551.766723499157, 15883.047017179006, 15046.54860354191, 15372.692546149014, 15448.536543903701, 15459.845401546065, 15541.533753987398, 9540.761480962892, 9666.371685840399, 9799.11807937289, 9818.459984700767, 9841.55712992917, 6888.345607167311, 7240.746573869889, 7596.886046709194, 7637.732679356418, 7669.808254793675, 13541.932290338485, 13761.17432954026, 13861.376238069513, 13877.236751978739, 13941.651458713208, 13612.86387136116, 14024.08843425174, 14035.368408421442, 14123.43506259738, 14223.64440888986, 9442.669506567237, 9673.177779888789, 9694.982026887075, 9840.0, 9842.557084931052, 11062.336259824437, 11135.610253641084, 11168.776624467302, 11191.72020699489, 11210.495527701436, 8845.380823342304, 8983.46099234048, 9003.954980225057, 9243.2143267823, 9269.629214831331, 8819.955900220715, 8934.955325223333, 9001.864972025294, 9041.231495322709, 9056.909430905607, 8406.537640429835, 8590.570471476527, 8595.52724600153, 8601.741947741577, 8609.0, 6299.0, 6351.68241587928, 6352.333005034405, 6409.7436102555375, 6581.473482121514, 6295.9370406297185, 6403.679816009274, 6410.423061924449, 6430.678466076584, 6705.966470167813], "value": 5567.616104667908, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [388.7045845157679, 409.5331322292589, 409.7725762201978, 412.6059613069517, 412.9421880936669, 5529.391766905637, 5731.392216541474, 5782.76868925243, 5837.532997360212, 5844.795432159875, 5544.16837474379, 5681.0, 5731.598788084836, 5734.971325143369, 5742.0, 4553.2942393929, 4556.270996640538, 4563.885902852422, 4571.839985600504, 4577.56513131252, 4431.512533621315, 4521.457425108992, 4550.749708766017, 4552.521985191557, 4562.520935301779, 4489.25927222008, 4513.255312873372, 4516.119356725423, 4517.435320584927, 4521.1409832131785, 4528.637708983307, 4529.320601909707, 4532.252178390561, 4534.433195850502, 4538.773061346952, 4044.312466880637, 4078.490188726409, 4089.9182016359805, 4113.773742444165, 4117.949922769674, 5598.832035038955, 5599.160125981135, 5601.663900166003, 5612.793249451388, 5617.4382561743905, 5288.365396152448, 5330.227117068042, 5336.706481143536, 5357.142857142878, 5372.838814835562, 6010.338862725097, 6047.939520604826, 6071.150038994544, 6086.786962456337, 6092.4768807798055, 4575.107853968478, 4577.9542204577865, 4593.977030114861, 4596.402467679214, 4600.309953506967, 6138.570300078996, 6212.844678883019, 6273.905891411679, 6274.121622972787, 6299.559030867841, 4689.788959496831, 4715.646326525524, 4727.338172655831, 4751.073540659608, 4751.239801631722, 4776.331313616097, 4797.544233297831, 4798.808047678054, 4800.759962001886, 4802.471728109905, 5253.212018197301, 5291.0, 5324.387695415076, 5331.866703332447, 5344.518993290622, 5818.301803783488, 5899.852503687356, 6087.65625234288, 6119.694015299217, 6142.539309551801, 1597.8002749656293, 1646.5554300338842, 1661.3271624991899, 1662.168915542229, 1669.7078011347965, 1162.9127815413708, 1190.7380376317196, 1215.7386161975041, 1216.5194748074462, 1219.8353222314875, 5826.912596311059, 5831.62094463867, 5858.2677165354335, 5859.824205273849, 5863.589548731547, 6193.964957171044, 6219.782307619302, 6222.284437289679, 6235.688215589203, 6247.656378899113, 5687.630304030225, 5788.565857560616, 5827.621204621686, 5855.590108692436, 5865.384134665798, 3261.445554255782, 3271.987727547541, 3280.114369120372, 3287.474004159323, 3291.374638818617, 6269.498440124779, 6467.159269295055, 6467.320931302145, 6475.061116138228, 6540.4767618590395, 6417.903731444032, 6434.7104380303, 6468.838279043061, 6470.773522926675, 6475.611463312216, 6291.653959032297, 6429.903551446826, 6537.869242615121, 6558.213014438298, 6575.901361479581, 6270.0, 6282.900492413901, 6285.59143655661, 6300.9684951574945, 6359.17330747, 6005.429484198994, 6219.129321894848, 6264.655443950626, 6275.623462592076, 6275.838969790603, 6352.587081839665, 6371.069308118317, 6385.808425747235, 6394.2646595640235, 6489.4808415325715, 4239.766812825324, 4244.893877653083, 4246.8725938221905, 4259.169461955013, 4265.040365917768, 6210.596311239755, 6269.12232287471, 6294.370562943852, 6297.842553936188, 6304.3380445054445, 6085.6348619083, 6120.479759220516, 6122.632642041492, 6125.234345706786, 6136.189824002019, 6000.279966404059, 6013.639181649115, 6112.960796664666, 6129.141920131096, 6143.815685529441, 6059.484943779652, 6069.150318955261, 6072.271327440559, 6078.787242446493, 6126.549380247937, 6025.909611355745, 6109.75560977556, 6115.388461154027, 6148.231471066117, 6158.692065396623, 5886.087656413263, 5899.203607512931, 5907.5273978080795, 5961.612495187799, 5965.0], "value": 4713.932943330151, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [851.3572252949023, 851.3955091884761, 871.8779370888072, 876.3208513402117, 879.028673315986, 16745.162741862918, 16830.747538786927, 16986.745198822013, 17367.13164341784, 17369.826301736994, 15789.60525986851, 16729.581760455996, 16818.83181168188, 17218.569535761613, 17232.56918577033, 995.5221493683036, 1016.8779746430439, 1026.5021464589668, 1028.8971102889725, 1029.3978022856638, 1029.0841151375282, 1032.184574186392, 1032.406366339354, 1032.7314898126467, 1032.9431881246528, 1073.0717928991405, 1077.6874706335154, 1080.9135269178446, 1081.3565928272665, 1089.9727506812353, 1065.8774240962234, 1072.6299426697815, 1073.2058276875096, 1073.3291692692067, 1082.1288862465713, 785.5679376342988, 785.6621652689341, 786.2373497707272, 787.6416230615056, 789.6722860013145, 15810.683786324209, 15968.441104561285, 17349.479515614552, 17444.0, 17510.387136450292, 17127.74308385375, 17141.400050998156, 17942.820571794375, 17966.730499042525, 18087.457376278733, 13934.233617151052, 14166.716665666629, 14406.0, 14435.566932992027, 15132.924335378362, 3409.3863104641155, 3511.0169152637304, 5074.873128171788, 5101.107306221396, 5110.9744451277875, 87881.0, 88027.55986220091, 89401.55299223462, 90208.0, 90294.0, 14198.290085495684, 14390.568282951528, 14425.567232983027, 14523.458394781082, 14541.709165816625, 26662.06682766094, 26726.19821405361, 26844.463110737677, 26916.0, 27463.450730985267, 7695.730649427243, 7783.0660320760735, 7812.960935195288, 7833.5691536966015, 7834.764957051298, 4028.617281358266, 4125.29869922114, 4143.709940304149, 4144.3990621359735, 4144.730592511477, 4322.978385108117, 4401.339799030107, 4444.866654000385, 4481.551844815493, 4494.9325760113625, 794.4399198564997, 804.4610111225493, 811.1037303779357, 815.3925325632323, 815.7797394703515, 659.7262136213513, 675.3482889012029, 678.884589619756, 687.2817905288985, 689.5483458334699, 11137.276077054965, 12732.618021459371, 12738.172018818746, 12853.164544304776, 12882.09825312219, 721.1634503975364, 725.8802297620833, 736.716364199787, 746.3208480283025, 751.1887161865172, 5184.922226166611, 5338.733063346817, 5357.705326207096, 5415.891682166334, 5473.397926228112, 80734.596327018, 83170.58414707887, 84442.57778711067, 85208.57395712982, 87543.56228218818, 74314.25685743181, 75107.62446187733, 75418.99999999891, 75924.0, 78542.6072869632, 64047.0, 65726.67136664285, 65855.67072164608, 66918.3308166912, 67187.66406168036, 10717.196210284104, 11202.439878005904, 11214.719632008939, 11413.885861141282, 11442.542298308308, 35487.467687984186, 35816.64183358133, 36058.63941360553, 37001.92210016775, 37175.81412092922, 41955.7902210487, 42773.78613107039, 42989.48610395988, 43713.344299836164, 44120.0, 14402.495912643008, 14464.710705786036, 14666.85333146655, 14729.779053313994, 14917.328720207399, 16206.594835128744, 16533.173341332644, 16646.334146634, 16681.33274669048, 16833.663326733153, 14896.925515372353, 14907.403703851724, 14953.0, 15067.4726384576, 15252.466163684217, 14085.15489070659, 14092.577222682925, 14116.153030818185, 14213.857861421253, 14371.353289101806, 15535.0, 17641.82358176402, 18369.81630183681, 18528.629427411644, 18739.625207496047, 12197.878021219674, 12580.87419125797, 12646.430910609228, 12794.36028198605, 13693.041487096207, 14334.068285561405, 14785.92607036958, 14805.555833325017, 15061.912307977058, 15223.16272604973], "value": 8196.029440700335, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1477.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 112.0, 110.0, 108.0, 110.0, 110.0, 111.0, 109.0, 109.0, 110.0], "value": 109.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 117.0, 119.0, 172.0, 117.0, 117.0, 118.0, 116.0, 147.0, 117.0], "value": 128.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 161.0, 162.0, 162.0, 160.0, 162.0, 161.0, 160.0, 163.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 74.0, 113.0, 74.0, 74.0, 74.0, 73.0, 97.0, 76.0], "value": 80.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 106.0, 82.0, 84.0, 82.0, 95.0, 82.0, 82.0, 107.0], "value": 88.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 112.0, 113.0], "value": 112.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 146.0, 147.0, 147.0, 147.0, 146.0, 147.0, 147.0, 146.0], "value": 146.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 96.0, 93.0, 94.0, 94.0, 94.0, 94.0, 94.0, 95.0, 94.0], "value": 94.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 49.0, 48.0, 49.0, 53.0, 48.0, 51.0, 50.0, 49.0], "value": 49.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 54.0, 55.0, 54.0, 53.0, 55.0, 54.0, 55.0, 55.0], "value": 54.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 78.0, 78.0, 78.0, 79.0, 79.0, 85.0, 80.0, 79.0, 79.0], "value": 80.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 126.0, 125.0, 131.0, 122.0, 123.0, 121.0, 122.0, 170.0, 122.0], "value": 128.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 179.0, 180.0, 177.0, 178.0, 179.0, 177.0, 170.0, 178.0, 180.0], "value": 178.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 64.0, 68.0, 65.0, 64.0, 63.0, 64.0, 62.0, 63.0], "value": 64.4, "unit": "score"}]}]} 09:35:08 INFO - Return code: 0 09:35:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:35:08 INFO - # TBPL SUCCESS # 09:35:08 INFO - Running post-action listener: _resource_record_post_action 09:35:08 INFO - [mozharness: 2017-05-12 16:35:08.767081Z] Finished run-tests step (success) 09:35:08 INFO - Running post-run listener: _resource_record_post_run 09:35:08 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 4096; Write bytes: 381497344; Read time: 12; Write time: 248192 09:35:08 INFO - TinderboxPrint: CPU usage
12.9% 09:35:08 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 09:35:08 INFO - TinderboxPrint: I/O write bytes / time
381,497,344 / 248,192 09:35:08 INFO - TinderboxPrint: CPU idle
8,294.6 (87.1%) 09:35:08 INFO - TinderboxPrint: CPU user
1,185.0 (12.4%) 09:35:08 INFO - TinderboxPrint: Swap in / out
0 / 0 09:35:08 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 112009216; Read time: 0; Write time: 95948 09:35:08 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 0; Write bytes: 269488128; Read time: 0; Write time: 152244 09:35:09 INFO - Running post-run listener: _upload_blobber_files 09:35:09 INFO - Blob upload gear active. 09:35:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:35:09 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:35:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:35:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:35:09 INFO - (blobuploader) - INFO - Open directory for files ... 09:35:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:35:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:35:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:35:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:35:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:35:12 INFO - (blobuploader) - INFO - Done attempting. 09:35:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:35:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:35:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:35:14 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:35:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:35:14 INFO - (blobuploader) - INFO - Done attempting. 09:35:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:35:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:35:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:35:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:35:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:35:14 INFO - (blobuploader) - INFO - Done attempting. 09:35:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:35:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:35:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:35:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:35:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:35:15 INFO - (blobuploader) - INFO - Done attempting. 09:35:15 INFO - (blobuploader) - INFO - Iteration through files over. 09:35:15 INFO - Return code: 0 09:35:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:35:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:35:15 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4818c73663a21d5266315aeba38ef382cdd8ded7f4e3a554b2f69f2cb5cc2b992ab708fe5c200a1ae9e8cf9f2339131d04abce3dd7e93bc77c673ad9eb95f2cc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/711c5688dd2de903681eb1f7c3597eeb177c9ffc259702fd0c9d9518ccefa2d160aefaa1453dfda4d1b4b8adcf6f3b637ed3fe5a53767562dab1b5f9c08d78d6", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/57acea9af44fec511f5ee781192469ddd313be3baa391b10981a3b6637d6f375ffa5203cfa79ba36910b0ba72c9e9c45457ebf41c5a6e828b056b4f5297cf8dd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5a545433190abe17c8f0fa465716d77585905512689d5c309aca7fa0d667317f88434eb1874c0c0c5ebc8a7a3b70b03519c269ea90723ba40afa9d8819a8ead6"} 09:35:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:35:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:35:15 INFO - Contents: 09:35:15 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4818c73663a21d5266315aeba38ef382cdd8ded7f4e3a554b2f69f2cb5cc2b992ab708fe5c200a1ae9e8cf9f2339131d04abce3dd7e93bc77c673ad9eb95f2cc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/711c5688dd2de903681eb1f7c3597eeb177c9ffc259702fd0c9d9518ccefa2d160aefaa1453dfda4d1b4b8adcf6f3b637ed3fe5a53767562dab1b5f9c08d78d6", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/57acea9af44fec511f5ee781192469ddd313be3baa391b10981a3b6637d6f375ffa5203cfa79ba36910b0ba72c9e9c45457ebf41c5a6e828b056b4f5297cf8dd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5a545433190abe17c8f0fa465716d77585905512689d5c309aca7fa0d667317f88434eb1874c0c0c5ebc8a7a3b70b03519c269ea90723ba40afa9d8819a8ead6"} 09:35:15 INFO - Running post-run listener: copy_logs_to_upload_dir 09:35:15 INFO - Copying logs to upload dir... 09:35:15 INFO - mkdir: /builds/slave/test/build/upload/logs 09:35:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1248.757719 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2017-05-12 09:35:15.729746) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-12 09:35:15.736053) ========= 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=03627c79f6bb3d1578c4a402000001ba-1494603943.276510-13598989 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4818c73663a21d5266315aeba38ef382cdd8ded7f4e3a554b2f69f2cb5cc2b992ab708fe5c200a1ae9e8cf9f2339131d04abce3dd7e93bc77c673ad9eb95f2cc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/711c5688dd2de903681eb1f7c3597eeb177c9ffc259702fd0c9d9518ccefa2d160aefaa1453dfda4d1b4b8adcf6f3b637ed3fe5a53767562dab1b5f9c08d78d6", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/57acea9af44fec511f5ee781192469ddd313be3baa391b10981a3b6637d6f375ffa5203cfa79ba36910b0ba72c9e9c45457ebf41c5a6e828b056b4f5297cf8dd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5a545433190abe17c8f0fa465716d77585905512689d5c309aca7fa0d667317f88434eb1874c0c0c5ebc8a7a3b70b03519c269ea90723ba40afa9d8819a8ead6"} build_url:https://queue.taskcluster.net/v1/task/RSLPZs3wTpaNujbNO4fCfw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011294 build_url: 'https://queue.taskcluster.net/v1/task/RSLPZs3wTpaNujbNO4fCfw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4818c73663a21d5266315aeba38ef382cdd8ded7f4e3a554b2f69f2cb5cc2b992ab708fe5c200a1ae9e8cf9f2339131d04abce3dd7e93bc77c673ad9eb95f2cc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/711c5688dd2de903681eb1f7c3597eeb177c9ffc259702fd0c9d9518ccefa2d160aefaa1453dfda4d1b4b8adcf6f3b637ed3fe5a53767562dab1b5f9c08d78d6", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/57acea9af44fec511f5ee781192469ddd313be3baa391b10981a3b6637d6f375ffa5203cfa79ba36910b0ba72c9e9c45457ebf41c5a6e828b056b4f5297cf8dd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5a545433190abe17c8f0fa465716d77585905512689d5c309aca7fa0d667317f88434eb1874c0c0c5ebc8a7a3b70b03519c269ea90723ba40afa9d8819a8ead6"}' ========= master_lag: 0.20 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-12 09:35:15.944920) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 09:35:15.945256) ========= 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=03627c79f6bb3d1578c4a402000001ba-1494603943.276510-13598989 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004505 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 09:35:15.966452) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-12 09:35:15.970740) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-12 09:35:15.971409) ========= ========= Total master_lag: 0.32 =========