builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-004 starttime: 1501658070.55 results: success (0) revision: 51ffb9283f0c7c00e08eb8c39b33fbee218c370d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-02 00:14:30.551631) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-02 00:14:30.551985) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-02 00:14:30.552415) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-02 00:14:30.598694) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-02 00:14:30.599031) ========= 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=c6927cf182745ad25b1d38b9000001fe-1501645127.809833-2090461881 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-02 00:14:30-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 42.8M=0s 2017-08-02 00:14:31 (42.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.152489 ========= master_lag: 0.07 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-02 00:14:30.820805) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-02 00:14:30.821083) ========= 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=c6927cf182745ad25b1d38b9000001fe-1501645127.809833-2090461881 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022485 ========= master_lag: 0.08 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-02 00:14:30.919983) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-02 00:14:30.920301) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 51ffb9283f0c7c00e08eb8c39b33fbee218c370d --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 51ffb9283f0c7c00e08eb8c39b33fbee218c370d --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=c6927cf182745ad25b1d38b9000001fe-1501645127.809833-2090461881 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-02 00:14:31,249 truncating revision to first 12 chars 2017-08-02 00:14:31,249 Setting DEBUG logging. 2017-08-02 00:14:31,249 attempt 1/10 2017-08-02 00:14:31,249 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/51ffb9283f0c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-02 00:14:31,634 unpacking tar archive at: mozilla-central-51ffb9283f0c/testing/mozharness/ program finished with exit code 0 elapsedTime=0.776621 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-02 00:14:31.739041) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-02 00:14:31.742346) ========= script_repo_revision: 51ffb9283f0c7c00e08eb8c39b33fbee218c370d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-02 00:14:31.742713) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-02 00:14:31.742976) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-02 00:14:31.751507) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2017-08-02 00:14:31.751775) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=c6927cf182745ad25b1d38b9000001fe-1501645127.809833-2090461881 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 00:14:32 INFO - MultiFileLogger online at 20170802 00:14:32 in /builds/slave/test 00:14:32 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 00:14:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 00:14:32 INFO - {'append_to_log': False, 00:14:32 INFO - 'base_work_dir': '/builds/slave/test', 00:14:32 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 00:14:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 00:14:32 INFO - 'branch': 'Firefox-Non-PGO', 00:14:32 INFO - 'buildbot_json_path': 'buildprops.json', 00:14:32 INFO - 'code_coverage': False, 00:14:32 INFO - 'config_files': ('talos/linux_config.py',), 00:14:32 INFO - 'default_actions': ('clobber', 00:14:32 INFO - 'read-buildbot-config', 00:14:32 INFO - 'download-and-extract', 00:14:32 INFO - 'populate-webroot', 00:14:32 INFO - 'create-virtualenv', 00:14:32 INFO - 'install', 00:14:32 INFO - 'setup-mitmproxy', 00:14:32 INFO - 'run-tests'), 00:14:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 00:14:32 INFO - 'disable_ccov_upload': False, 00:14:32 INFO - 'download_minidump_stackwalk': True, 00:14:32 INFO - 'download_symbols': 'ondemand', 00:14:32 INFO - 'enable_stylo': False, 00:14:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 00:14:32 INFO - 'tooltool.py': '/tools/tooltool.py', 00:14:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 00:14:32 INFO - '/tools/misc-python/virtualenv.py')}, 00:14:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 00:14:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 00:14:32 INFO - 'gecko_profile': False, 00:14:32 INFO - 'gecko_profile_interval': 0, 00:14:32 INFO - 'installer_path': 'installer.exe', 00:14:32 INFO - 'log_level': 'info', 00:14:32 INFO - 'log_name': 'talos', 00:14:32 INFO - 'log_to_console': True, 00:14:32 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 00:14:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 00:14:32 INFO - 'opt_config_files': (), 00:14:32 INFO - 'pip_index': False, 00:14:32 INFO - 'suite': 'dromaeojs-e10s', 00:14:32 INFO - 'system_bits': '32', 00:14:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 00:14:32 INFO - 'title': 'talos-linux64-ix-004', 00:14:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 00:14:32 INFO - 'use_talos_json': True, 00:14:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 00:14:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 00:14:32 INFO - 'work_dir': 'build'} 00:14:32 INFO - [mozharness: 2017-08-02 07:14:32.163704Z] Running clobber step. 00:14:32 INFO - Running pre-action listener: _resource_record_pre_action 00:14:32 INFO - Running main action method: clobber 00:14:32 INFO - rmtree: /builds/slave/test/build 00:14:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 00:14:33 INFO - Running post-action listener: _resource_record_post_action 00:14:33 INFO - [mozharness: 2017-08-02 07:14:33.195792Z] Finished clobber step (success) 00:14:33 INFO - [mozharness: 2017-08-02 07:14:33.195895Z] Running read-buildbot-config step. 00:14:33 INFO - Running pre-action listener: _resource_record_pre_action 00:14:33 INFO - Running main action method: read_buildbot_config 00:14:33 INFO - Using buildbot properties: 00:14:33 INFO - { 00:14:33 INFO - "project": "", 00:14:33 INFO - "product": "firefox", 00:14:33 INFO - "who": "archaeopteryx@coole-files.de", 00:14:33 INFO - "installer_path": "public/build/target.tar.bz2", 00:14:33 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 00:14:33 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 00:14:33 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 00:14:33 INFO - "stage_platform": "linux64", 00:14:33 INFO - "basedir": "/builds/slave/test", 00:14:33 INFO - "buildnumber": 751, 00:14:33 INFO - "platform": "ubuntu64_hw", 00:14:33 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 00:14:33 INFO - "slavebuilddir": "test", 00:14:33 INFO - "taskId": "IGWFBgMnTGiHbvikxBnKmg", 00:14:33 INFO - "branch": "mozilla-central", 00:14:33 INFO - "script_repo_revision": "production", 00:14:33 INFO - "revision": "51ffb9283f0c7c00e08eb8c39b33fbee218c370d", 00:14:33 INFO - "slavename": "talos-linux64-ix-004", 00:14:33 INFO - "repo_path": "mozilla-central" 00:14:33 INFO - } 00:14:33 INFO - Finding installer, test and symbols from parent task. 00:14:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IGWFBgMnTGiHbvikxBnKmg'}, attempt #1 00:14:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IGWFBgMnTGiHbvikxBnKmg'}, attempt #1 00:14:34 INFO - Task dependencies: JhuDbI3YQeS1KwM6F3P5lg 00:14:34 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JhuDbI3YQeS1KwM6F3P5lg'}, attempt #1 00:14:34 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/JhuDbI3YQeS1KwM6F3P5lg/artifacts/public/build/target.tar.bz2 00:14:34 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/JhuDbI3YQeS1KwM6F3P5lg/artifacts/public/build/target.test_packages.json 00:14:34 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/JhuDbI3YQeS1KwM6F3P5lg/artifacts/public/build/target.crashreporter-symbols.zip 00:14:34 INFO - Running post-action listener: _resource_record_post_action 00:14:34 INFO - [mozharness: 2017-08-02 07:14:34.753187Z] Finished read-buildbot-config step (success) 00:14:34 INFO - [mozharness: 2017-08-02 07:14:34.753410Z] Running download-and-extract step. 00:14:34 INFO - Running pre-action listener: _resource_record_pre_action 00:14:34 INFO - Running main action method: download_and_extract 00:14:34 INFO - mkdir: /builds/slave/test/build/tests 00:14:34 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')]} 00:14:34 INFO - trying https://queue.taskcluster.net/v1/task/JhuDbI3YQeS1KwM6F3P5lg/artifacts/public/build/target.test_packages.json 00:14:34 INFO - Downloading https://queue.taskcluster.net/v1/task/JhuDbI3YQeS1KwM6F3P5lg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 00:14:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JhuDbI3YQeS1KwM6F3P5lg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 00:14:35 INFO - Downloaded 989 bytes. 00:14:35 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 00:14:35 INFO - Using the following test package requirements: 00:14:35 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 00:14:35 INFO - u'common': [u'target.common.tests.zip'], 00:14:35 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 00:14:35 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 00:14:35 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 00:14:35 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 00:14:35 INFO - u'mozbase': [u'target.common.tests.zip'], 00:14:35 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 00:14:35 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 00:14:35 INFO - u'web-platform': [u'target.common.tests.zip', 00:14:35 INFO - u'target.web-platform.tests.tar.gz'], 00:14:35 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 00:14:35 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 00:14:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JhuDbI3YQeS1KwM6F3P5lg/artifacts/public/build/target.common.tests.zip 00:14:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JhuDbI3YQeS1KwM6F3P5lg/artifacts/public/build/target.common.tests.zip'}, attempt #1 00:14:35 INFO - Fetch https://queue.taskcluster.net/v1/task/JhuDbI3YQeS1KwM6F3P5lg/artifacts/public/build/target.common.tests.zip into memory 00:14:36 INFO - Content-Length response header: 72390374 00:14:36 INFO - Bytes received: 72390374 00:14:47 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 00:14:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JhuDbI3YQeS1KwM6F3P5lg/artifacts/public/build/target.common.tests.zip 00:14:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JhuDbI3YQeS1KwM6F3P5lg/artifacts/public/build/target.common.tests.zip'}, attempt #1 00:14:47 INFO - Fetch https://queue.taskcluster.net/v1/task/JhuDbI3YQeS1KwM6F3P5lg/artifacts/public/build/target.common.tests.zip into memory 00:14:49 INFO - Content-Length response header: 72390374 00:14:49 INFO - Bytes received: 72390374 00:15:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JhuDbI3YQeS1KwM6F3P5lg/artifacts/public/build/target.talos.tests.zip 00:15:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JhuDbI3YQeS1KwM6F3P5lg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 00:15:01 INFO - Fetch https://queue.taskcluster.net/v1/task/JhuDbI3YQeS1KwM6F3P5lg/artifacts/public/build/target.talos.tests.zip into memory 00:15:01 INFO - Content-Length response header: 14496467 00:15:01 INFO - Bytes received: 14496467 00: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')]} 00:15:02 INFO - trying https://queue.taskcluster.net/v1/task/JhuDbI3YQeS1KwM6F3P5lg/artifacts/public/build/target.tar.bz2 00:15:02 INFO - Downloading https://queue.taskcluster.net/v1/task/JhuDbI3YQeS1KwM6F3P5lg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 00:15:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JhuDbI3YQeS1KwM6F3P5lg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 00:15:03 INFO - Downloaded 62292244 bytes. 00:15:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JhuDbI3YQeS1KwM6F3P5lg/artifacts/public/build/target.tar.bz2 00:15:03 INFO - mkdir: /builds/slave/test/properties 00:15:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 00:15:03 INFO - Writing to file /builds/slave/test/properties/build_url 00:15:03 INFO - Contents: 00:15:03 INFO - build_url:https://queue.taskcluster.net/v1/task/JhuDbI3YQeS1KwM6F3P5lg/artifacts/public/build/target.tar.bz2 00:15:03 INFO - Running post-action listener: _resource_record_post_action 00:15:03 INFO - Running post-action listener: set_extra_try_arguments 00:15:03 INFO - [mozharness: 2017-08-02 07:15:03.333674Z] Finished download-and-extract step (success) 00:15:03 INFO - [mozharness: 2017-08-02 07:15:03.333788Z] Running populate-webroot step. 00:15:03 INFO - Running pre-action listener: _resource_record_pre_action 00:15:03 INFO - Running main action method: populate_webroot 00:15:03 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 00:15:03 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 00:15:03 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 00:15:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 00:15:03 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 00:15:03 INFO - 'tests': ['damp', 'tps']}, 00:15:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 00:15:03 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 00:15:03 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 00:15:03 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 00:15:03 INFO - 'other-e10s': {'tests': ['a11yr', 00:15:03 INFO - 'ts_paint', 00:15:03 INFO - 'tpaint', 00:15:03 INFO - 'sessionrestore', 00:15:03 INFO - 'sessionrestore_many_windows', 00:15:03 INFO - 'sessionrestore_no_auto_restore', 00:15:03 INFO - 'tabpaint']}, 00:15:03 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 00:15:03 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 00:15:03 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 00:15:03 INFO - 'talos_options': ['--mitmproxy', 00:15:03 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 00:15:03 INFO - '--firstNonBlankPaint'], 00:15:03 INFO - 'tests': ['quantum_pageload_google', 00:15:03 INFO - 'quantum_pageload_youtube', 00:15:03 INFO - 'quantum_pageload_amazon', 00:15:03 INFO - 'quantum_pageload_facebook']}, 00:15:03 INFO - 'quantum-pageload-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 00:15:03 INFO - 'talos_options': ['--stylo', 00:15:03 INFO - '--mitmproxy', 00:15:03 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 00:15:03 INFO - '--firstNonBlankPaint'], 00:15:03 INFO - 'tests': ['quantum_pageload_google', 00:15:03 INFO - 'quantum_pageload_youtube', 00:15:03 INFO - 'quantum_pageload_amazon', 00:15:03 INFO - 'quantum_pageload_facebook']}, 00:15:03 INFO - 'svgr-e10s': {'tests': ['tsvgx', 00:15:03 INFO - 'tsvgr_opacity', 00:15:03 INFO - 'tart', 00:15:03 INFO - 'tscrollx', 00:15:03 INFO - 'cart', 00:15:03 INFO - 'tsvg_static']}, 00:15:03 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 00:15:03 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 00:15:03 INFO - 'talos_options': ['--stylo', 00:15:03 INFO - '--stylo-threads=1', 00:15:03 INFO - '--mitmproxy', 00:15:03 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 00:15:03 INFO - '--firstNonBlankPaint'], 00:15:03 INFO - 'tests': ['tp6_google', 00:15:03 INFO - 'tp6_youtube', 00:15:03 INFO - 'tp6_amazon', 00:15:03 INFO - 'tp6_facebook']}, 00:15:03 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 00:15:03 INFO - 'talos_options': ['--xperf_path', 00:15:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 00:15:03 INFO - 'tests': ['tp5n']}}} 00:15:03 INFO - Running post-action listener: _resource_record_post_action 00:15:03 INFO - [mozharness: 2017-08-02 07:15:03.342600Z] Finished populate-webroot step (success) 00:15:03 INFO - [mozharness: 2017-08-02 07:15:03.342690Z] Running create-virtualenv step. 00:15:03 INFO - Running pre-action listener: _resource_record_pre_action 00:15:03 INFO - Running main action method: create_virtualenv 00:15:03 INFO - Creating virtualenv /builds/slave/test/build/venv 00:15:03 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 00:15:03 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 00:15:03 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 00:15:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 00:15:03 INFO - Using real prefix '/usr' 00:15:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 00:15:03 INFO - Installing distribute.............................................................................................................................................................................................done. 00:15:05 INFO - Installing pip.................done. 00:15:05 INFO - Return code: 0 00:15:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 00:15:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 00:15:06 INFO - Reading from file tmpfile_stdout 00:15:06 INFO - Output received: 00:15:06 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 00:15:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 00: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')]} 00:15:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00: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')]} 00:15:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23c21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x258fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2732d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x272feb0>, '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 0x2730db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2731240>, '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': 'c6927cf182745ad25b1d38b9000001fe-1501645127.809833-2090461881', '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 00: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', 'pip>=1.5'] in /builds/slave/test/build 00: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 pip>=1.5 00:15:06 INFO - Using env: {'DISPLAY': ':0', 00:15:06 INFO - 'HOME': '/home/cltbld', 00:15:06 INFO - 'LANG': 'en_US.UTF-8', 00:15:06 INFO - 'LANGUAGE': 'en_US:en', 00:15:06 INFO - 'LOGNAME': 'cltbld', 00:15:06 INFO - 'MAIL': '/var/mail/cltbld', 00:15:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:15:06 INFO - 'MOZ_NO_REMOTE': '1', 00:15:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:15:06 INFO - 'NO_EM_RESTART': '1', 00:15:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:15:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:15:06 INFO - 'PWD': '/builds/slave/test', 00:15:06 INFO - 'SHELL': '/bin/bash', 00:15:06 INFO - 'SHLVL': '1', 00:15:06 INFO - 'TERM': 'linux', 00:15:06 INFO - 'TMOUT': '86400', 00:15:06 INFO - 'USER': 'cltbld', 00:15:06 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1501645127.809833-2090461881', 00:15:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:15:06 INFO - '_': '/tools/buildbot/bin/python'} 00:15:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:15:06 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 00:15:06 INFO - Cleaning up... 00:15:06 INFO - Return code: 0 00:15:06 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 00: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')]} 00:15:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00: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')]} 00:15:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00: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', '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 0x23c21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x258fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2732d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x272feb0>, '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 0x2730db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2731240>, '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': 'c6927cf182745ad25b1d38b9000001fe-1501645127.809833-2090461881', '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 00: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', 'psutil>=3.1.1'] in /builds/slave/test/build 00: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 psutil>=3.1.1 00:15:06 INFO - Using env: {'DISPLAY': ':0', 00:15:06 INFO - 'HOME': '/home/cltbld', 00:15:06 INFO - 'LANG': 'en_US.UTF-8', 00:15:06 INFO - 'LANGUAGE': 'en_US:en', 00:15:06 INFO - 'LOGNAME': 'cltbld', 00:15:06 INFO - 'MAIL': '/var/mail/cltbld', 00:15:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:15:06 INFO - 'MOZ_NO_REMOTE': '1', 00:15:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:15:06 INFO - 'NO_EM_RESTART': '1', 00:15:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:15:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:15:06 INFO - 'PWD': '/builds/slave/test', 00:15:06 INFO - 'SHELL': '/bin/bash', 00:15:06 INFO - 'SHLVL': '1', 00:15:06 INFO - 'TERM': 'linux', 00:15:06 INFO - 'TMOUT': '86400', 00:15:06 INFO - 'USER': 'cltbld', 00:15:06 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1501645127.809833-2090461881', 00:15:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:15:06 INFO - '_': '/tools/buildbot/bin/python'} 00:15:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:15:06 INFO - Downloading/unpacking psutil>=3.1.1 00: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 00: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 00: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 00: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 00:15:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 00:15:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 00:15:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 00:15:07 INFO - Installing collected packages: psutil 00:15:07 INFO - Running setup.py install for psutil 00:15:07 INFO - building 'psutil._psutil_linux' extension 00:15:07 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 00:15:07 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 00:15:08 INFO - building 'psutil._psutil_posix' extension 00:15:08 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 00:15:08 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 00:15:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 00:15:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 00:15:08 INFO - Successfully installed psutil 00:15:08 INFO - Cleaning up... 00:15:08 INFO - Return code: 0 00:15:08 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 00: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')]} 00:15:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00: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')]} 00:15:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:15:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x23c21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x258fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2732d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x272feb0>, '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 0x2730db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2731240>, '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': 'c6927cf182745ad25b1d38b9000001fe-1501645127.809833-2090461881', '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 00:15:08 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 00:15:08 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 00:15:08 INFO - Using env: {'DISPLAY': ':0', 00:15:08 INFO - 'HOME': '/home/cltbld', 00:15:08 INFO - 'LANG': 'en_US.UTF-8', 00:15:08 INFO - 'LANGUAGE': 'en_US:en', 00:15:08 INFO - 'LOGNAME': 'cltbld', 00:15:08 INFO - 'MAIL': '/var/mail/cltbld', 00:15:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:15:08 INFO - 'MOZ_NO_REMOTE': '1', 00:15:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:15:08 INFO - 'NO_EM_RESTART': '1', 00:15:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:15:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:15:08 INFO - 'PWD': '/builds/slave/test', 00:15:08 INFO - 'SHELL': '/bin/bash', 00:15:08 INFO - 'SHLVL': '1', 00:15:08 INFO - 'TERM': 'linux', 00:15:08 INFO - 'TMOUT': '86400', 00:15:08 INFO - 'USER': 'cltbld', 00:15:08 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1501645127.809833-2090461881', 00:15:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:15:08 INFO - '_': '/tools/buildbot/bin/python'} 00:15:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:15:08 INFO - Downloading/unpacking mozsystemmonitor==0.3 00:15:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:15:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:15:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:15:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:15:09 INFO - Downloading mozsystemmonitor-0.3.tar.gz 00:15:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 00:15:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 00:15:09 INFO - Installing collected packages: mozsystemmonitor 00:15:09 INFO - Running setup.py install for mozsystemmonitor 00:15:09 INFO - Successfully installed mozsystemmonitor 00:15:09 INFO - Cleaning up... 00:15:09 INFO - Return code: 0 00:15:09 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 00:15:09 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')]} 00:15:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:15:09 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')]} 00:15:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:15:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x23c21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x258fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2732d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x272feb0>, '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 0x2730db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2731240>, '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': 'c6927cf182745ad25b1d38b9000001fe-1501645127.809833-2090461881', '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 00:15:09 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 00:15:09 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 00:15:09 INFO - Using env: {'DISPLAY': ':0', 00:15:09 INFO - 'HOME': '/home/cltbld', 00:15:09 INFO - 'LANG': 'en_US.UTF-8', 00:15:09 INFO - 'LANGUAGE': 'en_US:en', 00:15:09 INFO - 'LOGNAME': 'cltbld', 00:15:09 INFO - 'MAIL': '/var/mail/cltbld', 00:15:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:15:09 INFO - 'MOZ_NO_REMOTE': '1', 00:15:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:15:09 INFO - 'NO_EM_RESTART': '1', 00:15:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:15:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:15:09 INFO - 'PWD': '/builds/slave/test', 00:15:09 INFO - 'SHELL': '/bin/bash', 00:15:09 INFO - 'SHLVL': '1', 00:15:09 INFO - 'TERM': 'linux', 00:15:09 INFO - 'TMOUT': '86400', 00:15:09 INFO - 'USER': 'cltbld', 00:15:09 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1501645127.809833-2090461881', 00:15:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:15:09 INFO - '_': '/tools/buildbot/bin/python'} 00:15:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:15:10 INFO - Downloading/unpacking jsonschema==2.5.1 00:15:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:15:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:15:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:15:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:15:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 00:15:11 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 00:15:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:15:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:15:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:15:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:15:11 INFO - Downloading functools32-3.2.3-2.tar.gz 00:15:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 00:15:11 INFO - warning: no files found matching '*.txt' 00:15:11 INFO - no previously-included directories found matching 'build' 00:15:11 INFO - no previously-included directories found matching 'dist' 00:15:11 INFO - no previously-included directories found matching '.git*' 00:15:11 INFO - Installing collected packages: jsonschema, functools32 00:15:11 INFO - Running setup.py install for functools32 00:15:11 INFO - warning: no files found matching '*.txt' 00:15:11 INFO - no previously-included directories found matching 'build' 00:15:11 INFO - no previously-included directories found matching 'dist' 00:15:11 INFO - no previously-included directories found matching '.git*' 00:15:11 INFO - Successfully installed jsonschema functools32 00:15:11 INFO - Cleaning up... 00:15:11 INFO - Return code: 0 00:15:11 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 00: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')]} 00:15:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00: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')]} 00:15:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:15:11 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 0x23c21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x258fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2732d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x272feb0>, '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 0x2730db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2731240>, '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': 'c6927cf182745ad25b1d38b9000001fe-1501645127.809833-2090461881', '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 00:15:11 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 00:15:11 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 00:15:11 INFO - Using env: {'DISPLAY': ':0', 00:15:11 INFO - 'HOME': '/home/cltbld', 00:15:11 INFO - 'LANG': 'en_US.UTF-8', 00:15:11 INFO - 'LANGUAGE': 'en_US:en', 00:15:11 INFO - 'LOGNAME': 'cltbld', 00:15:11 INFO - 'MAIL': '/var/mail/cltbld', 00:15:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:15:11 INFO - 'MOZ_NO_REMOTE': '1', 00:15:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:15:11 INFO - 'NO_EM_RESTART': '1', 00:15:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:15:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:15:11 INFO - 'PWD': '/builds/slave/test', 00:15:11 INFO - 'SHELL': '/bin/bash', 00:15:11 INFO - 'SHLVL': '1', 00:15:11 INFO - 'TERM': 'linux', 00:15:11 INFO - 'TMOUT': '86400', 00:15:11 INFO - 'USER': 'cltbld', 00:15:11 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1501645127.809833-2090461881', 00:15:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:15:11 INFO - '_': '/tools/buildbot/bin/python'} 00:15:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:15:11 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 00:15:11 INFO - Cleaning up... 00:15:11 INFO - Return code: 0 00:15:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 00: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')]} 00:15:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00: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')]} 00:15:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:15:11 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 0x23c21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x258fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2732d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x272feb0>, '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 0x2730db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2731240>, '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': 'c6927cf182745ad25b1d38b9000001fe-1501645127.809833-2090461881', '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 00:15:11 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 00:15:11 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 00:15:11 INFO - Using env: {'DISPLAY': ':0', 00:15:11 INFO - 'HOME': '/home/cltbld', 00:15:11 INFO - 'LANG': 'en_US.UTF-8', 00:15:11 INFO - 'LANGUAGE': 'en_US:en', 00:15:11 INFO - 'LOGNAME': 'cltbld', 00:15:11 INFO - 'MAIL': '/var/mail/cltbld', 00:15:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:15:11 INFO - 'MOZ_NO_REMOTE': '1', 00:15:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:15:11 INFO - 'NO_EM_RESTART': '1', 00:15:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:15:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:15:11 INFO - 'PWD': '/builds/slave/test', 00:15:11 INFO - 'SHELL': '/bin/bash', 00:15:11 INFO - 'SHLVL': '1', 00:15:11 INFO - 'TERM': 'linux', 00:15:11 INFO - 'TMOUT': '86400', 00:15:11 INFO - 'USER': 'cltbld', 00:15:11 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1501645127.809833-2090461881', 00:15:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:15:11 INFO - '_': '/tools/buildbot/bin/python'} 00:15:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:15:11 INFO - Downloading/unpacking blobuploader==1.2.4 00:15:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:15:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:15:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:15:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:15:13 INFO - Downloading blobuploader-1.2.4.tar.gz 00:15:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 00:15:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 00: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 00: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 00: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 00: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 00:15:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 00:15:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 00: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 00: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 00: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 00: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 00:15:13 INFO - Downloading docopt-0.6.1.tar.gz 00:15:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 00:15:13 INFO - Installing collected packages: blobuploader, requests, docopt 00:15:13 INFO - Running setup.py install for blobuploader 00:15:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 00:15:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 00:15:13 INFO - Running setup.py install for requests 00:15:14 INFO - Running setup.py install for docopt 00:15:14 INFO - Successfully installed blobuploader requests docopt 00:15:14 INFO - Cleaning up... 00:15:14 INFO - Return code: 0 00:15:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00: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')]} 00:15:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00: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')]} 00:15:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:15:14 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 0x23c21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x258fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2732d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x272feb0>, '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 0x2730db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2731240>, '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': 'c6927cf182745ad25b1d38b9000001fe-1501645127.809833-2090461881', '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 00:15:14 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 00:15:14 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 00:15:14 INFO - Using env: {'DISPLAY': ':0', 00:15:14 INFO - 'HOME': '/home/cltbld', 00:15:14 INFO - 'LANG': 'en_US.UTF-8', 00:15:14 INFO - 'LANGUAGE': 'en_US:en', 00:15:14 INFO - 'LOGNAME': 'cltbld', 00:15:14 INFO - 'MAIL': '/var/mail/cltbld', 00:15:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:15:14 INFO - 'MOZ_NO_REMOTE': '1', 00:15:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:15:14 INFO - 'NO_EM_RESTART': '1', 00:15:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:15:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:15:14 INFO - 'PWD': '/builds/slave/test', 00:15:14 INFO - 'SHELL': '/bin/bash', 00:15:14 INFO - 'SHLVL': '1', 00:15:14 INFO - 'TERM': 'linux', 00:15:14 INFO - 'TMOUT': '86400', 00:15:14 INFO - 'USER': 'cltbld', 00:15:14 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1501645127.809833-2090461881', 00:15:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:15:14 INFO - '_': '/tools/buildbot/bin/python'} 00:15:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:15:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 00:15:14 INFO - Running setup.py (path:/tmp/pip-F7BxdE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 00:15:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 00:15:14 INFO - Running setup.py (path:/tmp/pip-QG8dxx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 00:15:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 00:15:14 INFO - Running setup.py (path:/tmp/pip-ZXSswp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 00:15:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 00:15:14 INFO - Running setup.py (path:/tmp/pip-MAHEhw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 00:15:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 00:15:14 INFO - Running setup.py (path:/tmp/pip-Pw4kME-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 00:15:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 00:15:14 INFO - Running setup.py (path:/tmp/pip-26JWx8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 00:15:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 00:15:14 INFO - Running setup.py (path:/tmp/pip-AiiJK3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 00:15:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 00:15:14 INFO - Running setup.py (path:/tmp/pip-v0Kdua-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 00:15:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 00:15:15 INFO - Running setup.py (path:/tmp/pip-9Lq83x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 00:15:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 00:15:15 INFO - Running setup.py (path:/tmp/pip-vWdvYZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 00:15:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 00:15:15 INFO - Running setup.py (path:/tmp/pip-FRKeSJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 00:15:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 00:15:15 INFO - Running setup.py (path:/tmp/pip-lB2Wlw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 00:15:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 00:15:15 INFO - Running setup.py (path:/tmp/pip-z6XVCp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 00:15:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 00:15:15 INFO - Running setup.py (path:/tmp/pip-UNLJ0x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 00:15:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 00:15:15 INFO - Running setup.py (path:/tmp/pip-McdYW2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 00:15:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 00:15:15 INFO - Running setup.py (path:/tmp/pip-wdFfxs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 00:15:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 00:15:15 INFO - Running setup.py (path:/tmp/pip-Yy9dY9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 00:15:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 00:15:15 INFO - Running setup.py install for manifestparser 00:15:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 00:15:15 INFO - Running setup.py install for mozcrash 00:15:15 INFO - Running setup.py install for mozdebug 00:15:15 INFO - Running setup.py install for mozdevice 00:15:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 00:15:16 INFO - Running setup.py install for mozfile 00:15:16 INFO - Running setup.py install for mozhttpd 00:15:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 00:15:16 INFO - Running setup.py install for mozinfo 00:15:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 00:15:16 INFO - Running setup.py install for mozInstall 00:15:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 00:15:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 00:15:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 00:15:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 00:15:16 INFO - Running setup.py install for mozleak 00:15:16 INFO - Running setup.py install for mozlog 00:15:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 00:15:16 INFO - Running setup.py install for moznetwork 00:15:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 00:15:16 INFO - Running setup.py install for mozprocess 00:15:16 INFO - Running setup.py install for mozprofile 00:15:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 00:15:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 00:15:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 00:15:16 INFO - Running setup.py install for mozrunner 00:15:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 00:15:17 INFO - Running setup.py install for mozscreenshot 00:15:17 INFO - Running setup.py install for moztest 00:15:17 INFO - Running setup.py install for mozversion 00:15:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 00:15:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 00:15:17 INFO - Cleaning up... 00:15:17 INFO - Return code: 0 00:15:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:15:17 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')]} 00:15:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:15:17 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')]} 00:15:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:15:17 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 0x23c21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x258fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2732d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x272feb0>, '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 0x2730db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2731240>, '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': 'c6927cf182745ad25b1d38b9000001fe-1501645127.809833-2090461881', '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 00:15:17 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 00:15:17 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 00:15:17 INFO - Using env: {'DISPLAY': ':0', 00:15:17 INFO - 'HOME': '/home/cltbld', 00:15:17 INFO - 'LANG': 'en_US.UTF-8', 00:15:17 INFO - 'LANGUAGE': 'en_US:en', 00:15:17 INFO - 'LOGNAME': 'cltbld', 00:15:17 INFO - 'MAIL': '/var/mail/cltbld', 00:15:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:15:17 INFO - 'MOZ_NO_REMOTE': '1', 00:15:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:15:17 INFO - 'NO_EM_RESTART': '1', 00:15:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:15:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:15:17 INFO - 'PWD': '/builds/slave/test', 00:15:17 INFO - 'SHELL': '/bin/bash', 00:15:17 INFO - 'SHLVL': '1', 00:15:17 INFO - 'TERM': 'linux', 00:15:17 INFO - 'TMOUT': '86400', 00:15:17 INFO - 'USER': 'cltbld', 00:15:17 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1501645127.809833-2090461881', 00:15:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:15:17 INFO - '_': '/tools/buildbot/bin/python'} 00:15:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:15:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 00:15:17 INFO - Running setup.py (path:/tmp/pip-Hlu9at-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 00:15:17 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)) 00:15:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 00:15:17 INFO - Running setup.py (path:/tmp/pip-0CljII-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 00:15:17 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)) 00:15:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 00:15:17 INFO - Running setup.py (path:/tmp/pip-l_nt14-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 00:15:17 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)) 00:15:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 00:15:17 INFO - Running setup.py (path:/tmp/pip-10TESF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 00:15:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 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)) 00:15:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 00:15:17 INFO - Running setup.py (path:/tmp/pip-HfdH7y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 00:15:18 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)) 00:15:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 00:15:18 INFO - Running setup.py (path:/tmp/pip-9J7MEO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 00:15:18 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)) 00:15:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 00:15:18 INFO - Running setup.py (path:/tmp/pip-jUsAt5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 00:15:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 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)) 00:15:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 00:15:18 INFO - Running setup.py (path:/tmp/pip-gINWZL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 00:15:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 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)) 00:15:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 00:15:18 INFO - Running setup.py (path:/tmp/pip-MQyC0s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 00:15:18 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)) 00:15:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 00:15:18 INFO - Running setup.py (path:/tmp/pip-mH_hYm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 00:15:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 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)) 00:15:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 00:15:18 INFO - Running setup.py (path:/tmp/pip-cUiE4I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 00:15:18 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)) 00:15:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 00:15:18 INFO - Running setup.py (path:/tmp/pip-MP01Bx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 00:15:18 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)) 00:15:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 00:15:18 INFO - Running setup.py (path:/tmp/pip-AxewTn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 00:15:18 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)) 00:15:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 00:15:18 INFO - Running setup.py (path:/tmp/pip-p12STz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 00:15:18 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)) 00:15:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 00:15:18 INFO - Running setup.py (path:/tmp/pip-DqzUE1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 00:15:18 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)) 00:15:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 00:15:18 INFO - Running setup.py (path:/tmp/pip-lhPA2O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 00:15:18 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)) 00:15:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 00:15:18 INFO - Running setup.py (path:/tmp/pip-LViQWl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 00:15:18 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)) 00:15:18 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)) 00:15:18 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)) 00:15:18 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)) 00:15:18 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.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 00:15:18 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.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 00:15:18 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 00:15:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 00:15:18 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 00:15:18 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 00:15:18 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 00:15:18 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 00:15:20 INFO - Downloading blessings-1.6.tar.gz 00:15:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 00:15:20 INFO - Installing collected packages: blessings 00:15:20 INFO - Running setup.py install for blessings 00:15:20 INFO - Successfully installed blessings 00:15:20 INFO - Cleaning up... 00:15:20 INFO - Return code: 0 00:15:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 00:15:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 00:15:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 00:15:20 INFO - Reading from file tmpfile_stdout 00:15:20 INFO - Current package versions: 00:15:20 INFO - argparse == 1.2.1 00:15:20 INFO - blessings == 1.6 00:15:20 INFO - blobuploader == 1.2.4 00:15:20 INFO - docopt == 0.6.1 00:15:20 INFO - functools32 == 3.2.3-2 00:15:20 INFO - jsonschema == 2.5.1 00:15:20 INFO - manifestparser == 1.1 00:15:20 INFO - mozInstall == 1.13 00:15:20 INFO - mozcrash == 1.0 00:15:20 INFO - mozdebug == 0.1 00:15:20 INFO - mozdevice == 0.51 00:15:20 INFO - mozfile == 1.2 00:15:20 INFO - mozhttpd == 0.7 00:15:20 INFO - mozinfo == 0.10 00:15:20 INFO - mozleak == 0.1 00:15:20 INFO - mozlog == 3.5 00:15:20 INFO - moznetwork == 0.27 00:15:20 INFO - mozprocess == 0.25 00:15:20 INFO - mozprofile == 0.28 00:15:20 INFO - mozrunner == 6.13 00:15:20 INFO - mozscreenshot == 0.1 00:15:20 INFO - mozsystemmonitor == 0.3 00:15:20 INFO - moztest == 0.8 00:15:20 INFO - mozversion == 1.4 00:15:20 INFO - psutil == 3.1.1 00:15:20 INFO - requests == 1.2.3 00:15:20 INFO - wsgiref == 0.1.2 00:15:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:15:20 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')]} 00:15:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:15:20 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')]} 00:15:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:15:20 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 0x23c21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x258fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2732d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x272feb0>, '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 0x2730db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2731240>, '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': 'c6927cf182745ad25b1d38b9000001fe-1501645127.809833-2090461881', '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 00:15:20 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 00:15:20 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 00:15:20 INFO - Using env: {'DISPLAY': ':0', 00:15:20 INFO - 'HOME': '/home/cltbld', 00:15:20 INFO - 'LANG': 'en_US.UTF-8', 00:15:20 INFO - 'LANGUAGE': 'en_US:en', 00:15:20 INFO - 'LOGNAME': 'cltbld', 00:15:20 INFO - 'MAIL': '/var/mail/cltbld', 00:15:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:15:20 INFO - 'MOZ_NO_REMOTE': '1', 00:15:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:15:20 INFO - 'NO_EM_RESTART': '1', 00:15:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:15:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:15:20 INFO - 'PWD': '/builds/slave/test', 00:15:20 INFO - 'SHELL': '/bin/bash', 00:15:20 INFO - 'SHLVL': '1', 00:15:20 INFO - 'TERM': 'linux', 00:15:20 INFO - 'TMOUT': '86400', 00:15:20 INFO - 'USER': 'cltbld', 00:15:20 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1501645127.809833-2090461881', 00:15:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:15:20 INFO - '_': '/tools/buildbot/bin/python'} 00:15:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:15:20 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)) 00:15:20 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)) 00:15:20 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)) 00:15:20 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)) 00:15:20 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)) 00:15:20 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)) 00:15:20 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)) 00:15:20 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)) 00:15:20 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)) 00:15:20 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 00:15:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:15:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:15:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:15:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:15:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 00:15:22 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 00:15:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 00:15:22 INFO - Installing collected packages: simplejson 00:15:22 INFO - Running setup.py install for simplejson 00:15:22 INFO - building 'simplejson._speedups' extension 00:15:22 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 00:15:22 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 00:15:23 INFO - Successfully installed simplejson 00:15:23 INFO - Cleaning up... 00:15:23 INFO - Return code: 0 00:15:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 00:15:23 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')]} 00:15:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:15:23 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')]} 00:15:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:15:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x23c21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x258fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2732d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x272feb0>, '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 0x2730db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2731240>, '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': 'c6927cf182745ad25b1d38b9000001fe-1501645127.809833-2090461881', '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 00:15:23 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 00:15:23 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 00:15:23 INFO - Using env: {'DISPLAY': ':0', 00:15:23 INFO - 'HOME': '/home/cltbld', 00:15:23 INFO - 'LANG': 'en_US.UTF-8', 00:15:23 INFO - 'LANGUAGE': 'en_US:en', 00:15:23 INFO - 'LOGNAME': 'cltbld', 00:15:23 INFO - 'MAIL': '/var/mail/cltbld', 00:15:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:15:23 INFO - 'MOZ_NO_REMOTE': '1', 00:15:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:15:23 INFO - 'NO_EM_RESTART': '1', 00:15:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:15:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:15:23 INFO - 'PWD': '/builds/slave/test', 00:15:23 INFO - 'SHELL': '/bin/bash', 00:15:23 INFO - 'SHLVL': '1', 00:15:23 INFO - 'TERM': 'linux', 00:15:23 INFO - 'TMOUT': '86400', 00:15:23 INFO - 'USER': 'cltbld', 00:15:23 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1501645127.809833-2090461881', 00:15:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:15:23 INFO - '_': '/tools/buildbot/bin/python'} 00:15:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:15:23 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 00:15:23 INFO - Cleaning up... 00:15:23 INFO - Return code: 0 00:15:23 INFO - Running post-action listener: _resource_record_post_action 00:15:23 INFO - Running post-action listener: _start_resource_monitoring 00:15:23 INFO - Starting resource monitoring. 00:15:23 INFO - [mozharness: 2017-08-02 07:15:23.308480Z] Finished create-virtualenv step (success) 00:15:23 INFO - [mozharness: 2017-08-02 07:15:23.309000Z] Running install step. 00:15:23 INFO - Running pre-action listener: _resource_record_pre_action 00:15:23 INFO - Running main action method: install 00:15:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 00:15:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 00:15:23 INFO - Reading from file tmpfile_stdout 00:15:23 INFO - Detecting whether we're running mozinstall >=1.0... 00:15:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 00:15:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 00:15:23 INFO - Reading from file tmpfile_stdout 00:15:23 INFO - Output received: 00:15:23 INFO - Usage: mozinstall [options] installer 00:15:23 INFO - Options: 00:15:23 INFO - -h, --help show this help message and exit 00:15:23 INFO - -d DEST, --destination=DEST 00:15:23 INFO - Directory to install application into. [default: 00:15:23 INFO - "/builds/slave/test"] 00:15:23 INFO - --app=APP Application being installed. [default: firefox] 00:15:23 INFO - mkdir: /builds/slave/test/build/application 00:15:23 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'] 00:15:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 00:15:41 INFO - Reading from file tmpfile_stdout 00:15:41 INFO - Output received: 00:15:41 INFO - /builds/slave/test/build/application/firefox/firefox 00:15:41 INFO - Running post-action listener: _resource_record_post_action 00:15:41 INFO - [mozharness: 2017-08-02 07:15:41.703982Z] Finished install step (success) 00:15:41 INFO - [mozharness: 2017-08-02 07:15:41.704326Z] Running setup-mitmproxy step. 00:15:41 INFO - Running pre-action listener: _resource_record_pre_action 00:15:41 INFO - Running main action method: setup_mitmproxy 00:15:41 INFO - Skipping: mitmproxy is not required 00:15:41 INFO - Running post-action listener: _resource_record_post_action 00:15:41 INFO - [mozharness: 2017-08-02 07:15:41.706030Z] Finished setup-mitmproxy step (success) 00:15:41 INFO - [mozharness: 2017-08-02 07:15:41.706345Z] Running run-tests step. 00:15:41 INFO - Running pre-action listener: _resource_record_pre_action 00:15:41 INFO - Running pre-action listener: _set_gcov_prefix 00:15:41 INFO - Running main action method: run_tests 00:15:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 00:15:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 00:15:41 INFO - Python 2.7.3 00:15:41 INFO - Return code: 0 00:15:41 INFO - grabbing minidump binary from tooltool 00:15:41 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')]} 00:15:41 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 0x272feb0>, '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 0x2730db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2731240>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 00:15:41 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 00:15:41 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 00:15:41 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 00:15:41 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 00:15:41 INFO - Return code: 0 00:15:41 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 00:15:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 00:15:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 00:15:41 INFO - ENV: RUST_BACKTRACE is now 1 00:15:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 00:15:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 00:15:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JhuDbI3YQeS1KwM6F3P5lg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 00:15:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JhuDbI3YQeS1KwM6F3P5lg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-004 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 00:15:41 INFO - Using env: {'DISPLAY': ':0', 00:15:41 INFO - 'HOME': '/home/cltbld', 00:15:41 INFO - 'LANG': 'en_US.UTF-8', 00:15:41 INFO - 'LANGUAGE': 'en_US:en', 00:15:41 INFO - 'LOGNAME': 'cltbld', 00:15:41 INFO - 'MAIL': '/var/mail/cltbld', 00:15:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 00:15:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 00:15:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:15:41 INFO - 'MOZ_NO_REMOTE': '1', 00:15:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 00:15:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:15:41 INFO - 'NO_EM_RESTART': '1', 00:15:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:15:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:15:41 INFO - 'PWD': '/builds/slave/test', 00:15:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 00:15:41 INFO - 'RUST_BACKTRACE': '1', 00:15:41 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 00:15:41 INFO - 'SHELL': '/bin/bash', 00:15:41 INFO - 'SHLVL': '1', 00:15:41 INFO - 'TERM': 'linux', 00:15:41 INFO - 'TMOUT': '86400', 00:15:41 INFO - 'USER': 'cltbld', 00:15:41 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1501645127.809833-2090461881', 00:15:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:15:41 INFO - '_': '/tools/buildbot/bin/python'} 00:15:41 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JhuDbI3YQeS1KwM6F3P5lg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 00:15:42 INFO - mozversion application_buildid: 20170801092414 00:15:42 INFO - mozversion application_changeset: 51ffb9283f0c7c00e08eb8c39b33fbee218c370d 00:15:42 INFO - mozversion application_display_name: Nightly 00:15:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 00:15:42 INFO - mozversion application_name: Firefox 00:15:42 INFO - mozversion application_remotingname: firefox 00:15:42 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 00:15:42 INFO - mozversion application_vendor: Mozilla 00:15:42 INFO - mozversion application_version: 56.0a1 00:15:42 INFO - mozversion platform_buildid: 20170801092414 00:15:42 INFO - mozversion platform_changeset: 51ffb9283f0c7c00e08eb8c39b33fbee218c370d 00:15:42 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 00:15:42 INFO - mozversion platform_version: 56.0a1 00:15:42 INFO - using testdate: 1501658142 00:15:42 INFO - actual date: 1501658142 00:15:42 INFO - starting webserver on 'localhost:46453' 00:15:42 INFO - SUITE-START | Running 2 tests 00:15:42 INFO - TEST-START | dromaeo_css 00:15:42 INFO - Initialising browser for dromaeo_css test... 00:15:42 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:46453/getInfo.html -profile /tmp/tmpZImcvg/profile 00:15:42 INFO - TEST-INFO | started process 20196 (/builds/slave/test/build/application/firefox/firefox http://localhost:46453/getInfo.html) 00:15:48 INFO - TEST-INFO | 20196: exit 0 00:15:48 INFO - Browser initialized. 00:15:48 INFO - Running cycle 1/1 for dromaeo_css test... 00:15:48 INFO - TEST-INFO | started process 20401 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZImcvg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 00:15:49 INFO - PID 20401 | 00:15:49 INFO - PID 20401 | (/builds/slave/test/build/application/firefox/firefox:20450): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 00:15:49 INFO - PID 20401 | 00:15:59 INFO - PID 20401 | RSS: Main: 171536384 00:15:59 INFO - PID 20401 | 00:18:59 INFO - PID 20401 | Cycle 1(1): loaded http://localhost:46453/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46453/tests/dromaeo/cssquery-ext.html) 00:19:00 INFO - PID 20401 | RSS: Main: 171233280 00:19:00 INFO - PID 20401 | 00:22:02 INFO - PID 20401 | Cycle 1(1): loaded http://localhost:46453/tests/dromaeo/cssquery-ext.html (next: http://localhost:46453/tests/dromaeo/cssquery-jquery.html) 00:22:03 INFO - PID 20401 | RSS: Main: 170336256 00:22:03 INFO - PID 20401 | 00:25:05 INFO - PID 20401 | Cycle 1(1): loaded http://localhost:46453/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46453/tests/dromaeo/cssquery-mootools.html) 00:25:05 INFO - PID 20401 | RSS: Main: 173035520 00:25:05 INFO - PID 20401 | 00:28:07 INFO - PID 20401 | Cycle 1(1): loaded http://localhost:46453/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46453/tests/dromaeo/cssquery-prototype.html) 00:28:08 INFO - PID 20401 | RSS: Main: 171196416 00:28:08 INFO - PID 20401 | 00:31:12 INFO - PID 20401 | Cycle 1(1): loaded http://localhost:46453/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46453/tests/dromaeo/cssquery-yui.html) 00:31:12 INFO - PID 20401 | RSS: Main: 174718976 00:31:12 INFO - PID 20401 | 00:34:14 INFO - PID 20401 | Cycle 1(1): loaded http://localhost:46453/tests/dromaeo/cssquery-yui.html (next: http://localhost:46453/tests/dromaeo/cssquery-dojo.html) 00:34:14 INFO - PID 20401 | RSS: Main: 175206400 00:34:14 INFO - PID 20401 | 00:34:14 INFO - PID 20401 | __start_tp_report 00:34:14 INFO - PID 20401 | _x_x_mozilla_page_load 00:34:14 INFO - PID 20401 | _x_x_mozilla_page_load_details 00:34:14 INFO - PID 20401 | |i|pagename|runs| 00:34:14 INFO - PID 20401 | |0;dojo.html;3118.485449900768;3132.216945763559;3150.1494596458942;3157.510585859193;3158.210447388153;6170.876582468348;6191.62850228986;6195.566310358271;6233.345498722637;6259.280182778982;6101.359357267478;6180.783672571461;6200.596961197519;6208.317085120633;6296.905546416808;5650.180723795047;5711.400302968181;5728.369879313273;5732.455416735404;5786.016377215862;5689.374168841424;5745.885082298351;5785.797497087603;5793.275840519935;5819.447152520504;5715.714214289269;5731.627444216113;5776.046952252873;5786.56600754943;5836.2121113649755;5494.780208791643;5657.632253903483;5661.433856614306;5783.913241301384;5818.650880947135;5651.717414129318;5682.431756824326;5715.942840571583;5724.341700704389;5737.856553586152;6104.572679912408;6116.571839971204;6133.44798968095;6137.171481849938;6181.9690901545655;6653.800385988428;6690.933090669079;6715.2277488089;6721.260661327299;6761.628110453923;6702.631355275458;6769.289224631441;6798.354156355188;6799.762008329685;6809.216940051908;6189.319174890758;6223.439890409885;6233.906491402588;6288.968555157195;6317.305096439388;6696;6763.830904227434;6843;6894.103766510321;6898.793036208922;6592.143021407186;6607.141071660653;6617.503687223478;6656.83357916056;6662.467002639777;6672.799816005528;6685.565438246498;6743.2919543448215;6770.424513916372;6779.35596118368;6602.966985165043;6634.203895532471;6634.800955971328;6727.798166055026;6745.460363170934;6478.740850365933;6590.209174899042;6598.571092878948;6606.570572912745;6609.6364699941005;5286.947130528645;5292.947070529245;5294.814681486129;5294.841154765286;5297.311349524537;4547.818087276538;4585.403897493371;4601.930971035437;4604.700694454849;4613.215753321943;5714.142878568165;5721.370649228582;5722.313322401338;5724.198612194296;5729.312482502127;6589.670516474157;6605.976073708487;6607.141071660653;6619.304972977864;6619.371159739817;5654.74553645087;5720.971395143081;5795.94204057954;5799.159121927381;5892.086726557305;5850.063989761617;5918.970405147947;5929.080992446092;5939.406059394026;5943.10853371989;6337.04944258365;6434.549581529248;6456.677166141674;6531.216254049449;6581.078648989145;6646.335366463315;6658.5671931324305;6667.833304167435;6668.899966500409;6675.566088204251;5566.137248726453;6442.226932768098;6467.805965821033;6470.964645656861;6517.967410162919;6582.736690532325;6605.5376123670885;6671.866562668816;6672.299408562225;6692.163479565054;6499.155109835691;6624.867502649824;6647.60114393138;6783.728650854108;6789.592624442549;6456.838579035561;6459.547831651739;6471.482281417381;6472.190976127984;6495.772647957298;6414.518911081781;6445.452136568444;6459.9031014533875;6502.902456463252;6531.3468653134305;6388.8083357499345;6397.552171347961;6461.57999730016;6470.28826829039;6503.349665033459;6402.263739669997;6481;6492.35076492347;6640.070390145287;6640.501962352746;6304.464120549805;6314.810555683337;6397.200349956255;6421.967890160519;6424.486041116793 00:34:14 INFO - PID 20401 | |1;ext.html;839.5340585974782;888.4935586715573;902.3593248793356;917.7292698653898;1161.622472696374;31577.368452630963;32148;41826;42311.36532952008;42482.787586062106;42772.358414623806;42810.57189428105;42898.14203715932;43158.136837263235;43249.13501729971;7139.107611548557;7584.544927304351;7748.767536973872;7750.186230445806;7793.103793063785;7583.431242656795;7700.460967732233;7717.884231736529;7718.382529397635;7718.614069296541;7688.884666730003;7702.4993375430695;7726.8454630907345;7732.033495813023;7748.457607967444;7677.6161191940455;7682.193369696157;7700.075990881074;7710.730124445618;7715.845683086335;7595.848083038363;7669.156392796844;7670.194629563871;7671.808204794925;7686.039245094364;38224.61775382266;38744.61255387438;38940.80529597334;39019;39076.609233907584;3473.9131521711906;3500.8074555899416;3502.159481724393;3502.7548071634997;3504;32309.67690323114;32431.351372972407;32520.512192317135;32531.349373012643;32766;4816.157172494799;4863.221884498499;4891.1195984722735;4910.165271903785;4912.435069966964;183554;183661;184162.99999999866;184255;184335;49401.75299123481;49595;49643.75178124086;50073;50392.74803625958;53105.46894531005;53131.468685313426;53136.202956955676;53139.46860531423;53195;16461.176941153135;16625.584360390843;17068.658626827393;17093.743593846102;17248.913755431142;10597.89402105969;10643.361398316125;13458.394372253271;13722.245276509888;13742;14838.406463741545;14901.254937253094;14911.925440372946;14988.925055374653;15163.848361516244;5842.824715258549;5893.574096638266;5939.643621382731;6140.416660417253;6159.6612186329285;950.2777888804559;986.5659109991581;986.7286496213599;1007.5717819926502;1012.9848052279222;22481.437964050703;22765.88617056937;23417.7658223419;28132.71867281342;29828.850855745583;1075.4138994248153;1090.2695194219891;1099.4008265495324;1100.2023532938715;1108.562117963416;11924.642260732191;11991.520339186509;12357.93821030889;12381.133320667466;12445.001891337652;213832;216752;217331;217508;218042;54070;54070.45929540733;54525;54613.63465913384;54695;1455.1487379882676;1455.316001479303;1488.4790323386944;1496.91018538888;1500.189897455362;21962.215113954546;22049;22655;22983.65524517167;23149.074037039005;47868;48870.75564622154;48968.26547601718;49077;49080.263796042374;146071;146226;146520;146848;147464.26267868592;32056;32708;32712.509312359856;36691.44962825506;38297.23405531929;49158;49492.75253623853;49571;49970;49990.75004624954;33837.32325353529;34516.827415863765;34691.4796278051;34936;34955.65044349524;32190;33164.170895726835;34101.147471313416;34263;34743;37426;39493.40759888547;39530.60469395269;39696.801515992236;39816;34440.311193776484;35003.29993400168;35071.47392789059;35659.643403565635;35721.82139089288;24610.753892461562;25027.624585630867;25538.59537725443;25858.870705647103;26058.087966921066 00:34:14 INFO - PID 20401 | |2;jquery.html;580.2659635561015;583.883223355329;588.4233451217804;589.3634874335718;591.6272748168653;22482.213122540714;22487.662685059717;23113.422164445878;23297.417564560852;23369.18207862723;25622.615660765103;26057.60913586293;26176;26203.3449163771;26314.342141446425;6643.468522518199;6830.795076147699;6833.965830170842;6836.897446538306;6841.213260475034;5938.346781853993;6491.642959637218;6511.739530418778;6586.47308215344;6661.400473957343;6467.83830404239;6506.869862602745;6509.218893732758;6509.4466970307585;6511.772087976922;6439.356064393546;6472.805815825485;6493.383128602775;6497.447716944065;6501.902471462932;6027.7287522061615;6052.031674932034;6068.6662233577135;6073.119397687311;6075.756969721206;28944;29819.552706709415;30393.24016899573;30626.693733062606;30782.692173078205;1861;1895.0524737631185;1897.3548993342192;1900.6863867461852;1907.3705677126654;18965.43103706891;19389.321373751853;19577.706334404997;19586.70619940702;19597.80402195974;2400.538103186282;2405.7353691093967;2465.29739024379;2466.3464181991785;2466.90132394704;123933;130467.34766326203;131335.34332328278;131340.3432982829;132012;14846.331915063847;14981.70036599262;15237.238138093051;15303.311350989232;15574;23791.167309144097;23838.642420363707;23847;23935.16226932049;23982.160624378062;10526.210534209966;10580.89419105799;10642.680719578426;10657.84013239802;10794.73013174677;9954.651587194558;9955.751106222402;9976.102150806462;10093.545790439448;10113.241506887012;8933.865992010125;9135.40619859707;9434.367768409074;9594.136527712539;9623.326367154232;2873.161176846778;2878.136559032324;2885.1200383882697;2917.595877049855;2979.8957036503616;908.7818923458323;932.7574830544107;941.4069136444133;958.1903291718487;959.5154447004358;19355.709664354763;19407.417777466697;19489.122989465508;19525.511862203275;19987.400377988684;1021.5862575656871;1024.8667673202435;1031.2677998620914;1032.3650954662978;1045.994770026145;10558;10645.680629581126;11130.220884538005;12563.18339307942;13743.03798734099;460720;470515;473505;475856;480498;121482;124625.37687311505;125667.37166314293;125794;128194;1227.1042139238227;1252.4990003998291;1252.9686757831128;1254.0657210378145;1264.6901509130505;20014;21241;21323.727780497848;21407.250746223806;21440.78559214388;58652.70673646605;59495.40504594899;61767.07349389673;62450;62911.11266662007;74007;77642;78477;78769.60615196887;78984.21015789769;11783.175177737476;12366.690832729253;12369.93815030955;14687.779683304545;15036.173010484528;20030.799692003475;21045.684314735598;21244.25645102414;21541.569168616854;21610.675839862728;18136.546586335448;18155.909220453814;18342.90828545849;18410;18432.907835460737;14127.858721412655;15067.547973560811;15076.095434273979;15227.390904364145;15289.694206115593;16561.42035028768;17038;17355.3925612608;17436.215370308113;17538.561535961704;13185.53850615224;21176.78823211748;22197.667034994163;22343.77656223417;22410;18099.54751131232;18933.810661893756;19239.807601923803;19328;19342.806571934663 00:34:14 INFO - PID 20401 | |3;mootools.html;1303.341812384746;1367.234348764692;1391.1096897985292;1398.9720205595881;1404.705011947491;13738.793918091224;14331.1401315921;14348.06737562059;14350.928245358784;14352.138871667707;13678.658033549165;14658.48695295665;14794.334254958525;14799.852001479981;14817.407303707838;2619.790416766664;2686.8387896726163;2687.4625074984983;2688.1397952655157;2689.031948498549;2565.9358516037064;2590.5466543354837;2701.6217729517884;2705.296622878046;2754.669439667233;2682.222155574881;2716.633254510645;2716.7962402819767;2717.415755612543;2719.075514325129;2584.0697348954363;2592.7277635848245;2609.556375416184;2615.3853844346563;2620.737926207383;2582.21242521031;2610.3996080901497;2633.868306584663;2634.2492389669046;2637.274749443918;13718.519851805138;13954.302284885816;13966.720665586632;14058;14063.2968351582;7535.397168226528;7806.180351063113;7821.687132514692;7840.6863725451485;7845;4501.887452813673;4665.463471700764;4801.135795556798;5050.823221187277;5060.493950604947;1687.9831201687948;1688.653825965674;1710.529604358799;1729.80107287661;1739.7912250530017;23091.19180828663;23205.41986450352;23311.067557297687;23354.18260360879;23357.88321058401;6341.352611203301;6427.100205971168;6605.1083103780375;6608.801735947929;6623.20521537409;15381.9230903847;15442.45951391696;15616.76574851378;15642.139682317355;15666.530004099895;7475.70097196117;7654.349380302625;7712.691492340356;7727.806804829812;7739.264769846855;6743.831404214836;6974.616396098263;7127.7148914042855;7167.749128780468;7186.856262874818;5024.773885175176;5158.123119069767;5174.7930082796265;5197.66215196011;5200.141976573898;1366.760816857066;1412.9364178611986;1447.5874375802846;1482.495951376537;1668.4744305543716;823.197382552007;858.9785255368665;861.371199024703;865.2472349056361;885.9645614175361;7084.92915070853;7455.925440745632;7465.888011679829;7603.809904752426;7613.6573854176695;899.9415038022507;925.9305552083621;958.2382006305004;959.3716115944068;967.9225661947028;4411.404460397805;4605.884852878705;4643;4734.360861283682;4854.538818812207;18320.45038648843;19377.709334359995;19461.708074378897;19481.805181948;19499.8050019498;18315.816841831413;19180;19184.232630694616;19185.328513502238;19309.903450482656;10450.895491044992;10896.455177241107;10956.945215273872;10957.12343012573;11022.889771102347;6708.966455167693;7559.3196612305155;7612.619369031637;7850.214978502333;7982.441229113906;16597.58506037359;16678.66642667164;16712.49862504127;16801.495955121365;16924.492265232064;16014.359425622844;16590.004599724056;16749.413770517975;16881.40915067967;16929.23818428149;11519.884801152217;11712.88287117118;11734.765304694029;11753.236039657395;11763.117766167743;12767.680807979503;13537.1200871943;13590;13632.113912595649;13744.312784360542;10189.84715229287;10410.895891040993;10711.517981690999;10984.945075274893;11069.944650276697;10397;10415.114715249289;10415.94792026035;10447.05976462122;10697.14422846155;8553;8651.351148663749;8716.825663486821;8728.56357182151;8734.519601421984;6168.814935551941;6455.61266324022;6542.934570654233;6601.768938087144;6623.735050597922;6314.400131987454;6478.190226221723;6679.298673639198;6799.626020568916;6829.838927382456 00:34:14 INFO - PID 20401 | |4;prototype.html;486.67149673970073;495.11868873405353;521.2754271562527;527.3618921105463;531.3225637312429;6277.717502712378;6310.4636105930995;6320.8938435773725;6326.367363263671;6350.301466838644;5911.556633252501;6032.336442991278;6066.968615335392;6106.572539922207;6107.877842443148;4767.522068158882;4808.567228949394;4894.8286809961655;4935.383077115361;4975.477574854643;4849.733264670442;4889.242167464049;4904.969956309179;4914.576880807266;4919.7540122993705;4914.387924875214;5110.8756073663735;5126.974365128169;5153.123968925292;5172.818951336704;5079.390473143209;5087.3386459759995;5140.7172605506685;5152.252923326097;5190.506901844319;4733.881652958669;4748.928766068511;4754.7147171169845;4797.160496913027;4797.472278049447;6029.728662210211;6032.707571122989;6058.757649694007;6086.908696369558;6095.817125486243;5890.175375447441;5968.850778730566;5978.40215978403;6020.759169633209;6032.909506357408;6492.058651495507;6499.415052645238;6521.836954076139;6546.967265163691;6632.502562307846;5244.685318880879;5249.212618107276;5297.549708274802;5301.257823904656;5305.44292849253;6858.245592984817;6905.205901321312;6957.5129740918155;6988.79033628992;7003.439724822002;5358.356997160366;5391.973040134774;5448.727563621803;5461.044317244467;5504.614676972654;5509;5512.944870551243;5533.833984980457;5555.30558680165;5561.304836895388;5902.793402230901;5943.732532036069;6004.189434426384;6008.579399441997;6026.487748541335;6449.193850768654;6565.343465653396;6566.474682025522;6597.73609055642;6640.701168447432;1832.4044685477006;1882.6140641168527;1912.990435047816;1925.9229630814611;1928.517870532367;1356.382845805176;1423.7081398313321;1455.5997100797154;1470.624965657114;1474.2849717886816;5719.19931209631;5744.942550574524;5773;5776.249087618583;5785.971070144707;6521.706523206467;6581.473482121419;6603.405693487609;6659.766908158192;6681.42986398196;5628.831135065954;5634.098544232903;5755.913661295084;5756.366799652035;5798.362180160263;3474.513568100468;3524.3303772283184;3533.9823300883845;3535.646435356444;3543.805090720035;6447.935520644827;6551.344865513506;6561.475081993429;6568.807156163993;6581.407673309425;6808.480862081062;6814.2623630973785;6819.942908849136;6933.965330173317;6940.444764418834;6422.2614399344675;6439.259485159173;6484.967575162094;6534.346565343428;6547.705353259114;6571.277159512354;6650.002499625095;6705.765298214539;6721.596704197764;6748.865022699617;6582.341765823379;6628.867422651423;6641.1927774032;6693.364130407604;6736.4947628929;6511.576747511396;6526.967365163144;6559.934400656123;6563.245226798979;6564.4748420127235;4880.365552478155;4925.556699897026;4954.1330267202375;4954.380702412199;5000.449950505515;6504.512161587994;6640.701168447528;6659.768977392434;6748.966255168692;6754.966225168842;6441.355864413521;6499.772507962199;6501.544891857714;6553.934460655523;6600.372964568358;6366.6180029198395;6369.681515924278;6526.477881769352;6535.509836762167;6551.672416379067;6077.392260773887;6240.063990401476;6297.04791514632;6337.588056776295;6378.48972082223;6372.36276372359;6384.201974753156;6467.967660161858;6474.902876456951;6504.642244676399;6209.565330426827;6219.500621850087;6263.78076767311;6349.777757778455;6351.364863513611 00:34:14 INFO - PID 20401 | |5;yui.html;878.8725634782957;895.1004240738057;897.560195504203;909.4952301472682;914.1178762494192;16572.917135414322;16997.49007529776;17290.394836180752;17402.216900239488;20608.793912060875;19907;20709.378718638465;20918;20938.16247350108;20957.056932438038;1002.9168498022137;1028.7582418131733;1030.6701855406272;1030.7423144213947;1030.7577719235974;1049.2812423489895;1050.7058023753323;1050.8056009638242;1052.163529993657;1054.5834395413845;1030.9432981186033;1068.3322923173016;1073.4471747050275;1096.5504143301248;1104.7624760676454;1060.777236780277;1063.930844495109;1064.5209655654908;1064.973375665607;1066.248294952057;774.423054824154;774.6901239504188;779.1468342165376;781.1602527283193;781.6677911887482;16883.15584220796;17619.559511012198;18087.095645217687;18228.726569101473;18236.270549178018;20011.599768004704;20300.289489867784;20819.16723331065;20828.375148745563;21125.894370528204;15650.217489125613;15983.600409989727;16177.433789817418;16226.675466490722;16235.350585976546;3475.7045651119433;3508.736844736655;3508.947365789515;3511.052015955703;3513.402721537345;116488;118886.40556797161;121967.39016304951;124432.9999999991;124536;15652.843471565138;16090.436834710963;16204.43284485061;16210.270537825827;16212.918935405247;27285.45429091447;29355.55966660502;29387.23676579412;29435.558466623017;29469.705302947124;8075.798105047304;8184.877226841602;8233.300169485541;8692.434991725517;8705.521196334263;4160.18876319121;4293.313069908862;4293.334533147367;4296.033392486665;4297.205017071852;4727.101850648366;4827.589654879304;4830.565249127595;4837.975810120997;4888.046830868017;1031.886492485826;1046.6493724602344;1070.2936062198912;1074.065562960214;1075.2419544221304;654.8395643067479;673.2157037051878;689.899964505144;698.3889097040089;703.5532436902604;10675.306105103144;11985.760284794254;12134.817977730341;12241.571544995883;12591.055670824542;660.9173853268342;662.0797092042066;664.3954001858285;684.1106561470069;687.3195536418909;4923.630727695438;5187.221916712523;5201.349831271091;5207.635465517453;5321.946780532223;103951.4802425983;105258;105363;108098;109073.45463272791;84814.5759271212;86434;93280.53359733293;93355.53322233346;95477.5226123865;72305.2769472309;75293;76317.6184119087;77759.2224077752;78411;10460.372377657364;10537.315074520131;10836;11940.582079627171;12476.376181191086;33418.66581334253;34837;36015.63984360123;36111;36294.455583166804;63842.68078659763;64658;65131.34868651442;66739.66630166819;66931.66534167298;17484;18167.36414225496;18821.623567528848;19023.619527609648;19072.618547628692;18804.7179292308;19402.12690428906;19416.70874936849;19526.8047319525;19553.120109594816;17706.82293177052;17899.284028638707;17953.371631992817;18145.637087257917;18357.72463413023;17971.191296391957;18184.27262909469;18381.632367352846;18495.815041849946;18831.529211769815;17255.654886902445;17518.562035949202;17612.119394030502;17975.910120449837;18123.72814407811;11953.521859125538;12304;12951.870481295067;13329.866701332863;13663.521776737767;12783.360831958178;13203.273819939996;13427.798583021458;13450.596482105553;13932.164070155823 00:34:14 INFO - PID 20401 | __end_tp_report 00:34:14 INFO - PID 20401 | __start_cc_report 00:34:14 INFO - PID 20401 | _x_x_mozilla_cycle_collect,385 00:34:14 INFO - PID 20401 | __end_cc_report 00:34:14 INFO - PID 20401 | __startTimestamp1501659254687__endTimestamp 00:34:14 INFO - PID 20401 | 00:34:14 INFO - PID 20401 | ------- Summary: start ------- 00:34:14 INFO - PID 20401 | Number of tests: 6 00:34:14 INFO - PID 20401 | 00:34:14 INFO - PID 20401 | [#0] dojo.html Cycles:165 Average:6133.89 Median:6402.26 stddev:729.89 (11.4%) stddev-sans-first:692.73 00:34:14 INFO - PID 20401 | Values: 3118.5 3132.2 3150.1 3157.5 3158.2 6170.9 6191.6 6195.6 6233.3 6259.3 6101.4 6180.8 6200.6 6208.3 6296.9 5650.2 5711.4 5728.4 5732.5 5786.0 5689.4 5745.9 5785.8 5793.3 5819.4 5715.7 5731.6 5776.0 5786.6 5836.2 5494.8 5657.6 5661.4 5783.9 5818.7 5651.7 5682.4 5715.9 5724.3 5737.9 6104.6 6116.6 6133.4 6137.2 6182.0 6653.8 6690.9 6715.2 6721.3 6761.6 6702.6 6769.3 6798.4 6799.8 6809.2 6189.3 6223.4 6233.9 6289.0 6317.3 6696.0 6763.8 6843.0 6894.1 6898.8 6592.1 6607.1 6617.5 6656.8 6662.5 6672.8 6685.6 6743.3 6770.4 6779.4 6603.0 6634.2 6634.8 6727.8 6745.5 6478.7 6590.2 6598.6 6606.6 6609.6 5286.9 5292.9 5294.8 5294.8 5297.3 4547.8 4585.4 4601.9 4604.7 4613.2 5714.1 5721.4 5722.3 5724.2 5729.3 6589.7 6606.0 6607.1 6619.3 6619.4 5654.7 5721.0 5795.9 5799.2 5892.1 5850.1 5919.0 5929.1 5939.4 5943.1 6337.0 6434.5 6456.7 6531.2 6581.1 6646.3 6658.6 6667.8 6668.9 6675.6 5566.1 6442.2 6467.8 6471.0 6518.0 6582.7 6605.5 6671.9 6672.3 6692.2 6499.2 6624.9 6647.6 6783.7 6789.6 6456.8 6459.5 6471.5 6472.2 6495.8 6414.5 6445.5 6459.9 6502.9 6531.3 6388.8 6397.6 6461.6 6470.3 6503.3 6402.3 6481.0 6492.4 6640.1 6640.5 6304.5 6314.8 6397.2 6422.0 6424.5 00:34:14 INFO - PID 20401 | 00:34:14 INFO - PID 20401 | [#1] ext.html Cycles:180 Average:36656.74 Median:35365.56 stddev:47925.42 (135.5%) stddev-sans-first:47984.40 00:34:14 INFO - PID 20401 | Values: 839.5 888.5 902.4 917.7 1161.6 31577.4 32148.0 41826.0 42311.4 42482.8 42772.4 42810.6 42898.1 43158.1 43249.1 7139.1 7584.5 7748.8 7750.2 7793.1 7583.4 7700.5 7717.9 7718.4 7718.6 7688.9 7702.5 7726.8 7732.0 7748.5 7677.6 7682.2 7700.1 7710.7 7715.8 7595.8 7669.2 7670.2 7671.8 7686.0 38224.6 38744.6 38940.8 39019.0 39076.6 3473.9 3500.8 3502.2 3502.8 3504.0 32309.7 32431.4 32520.5 32531.3 32766.0 4816.2 4863.2 4891.1 4910.2 4912.4 183554.0 183661.0 184163.0 184255.0 184335.0 49401.8 49595.0 49643.8 50073.0 50392.7 53105.5 53131.5 53136.2 53139.5 53195.0 16461.2 16625.6 17068.7 17093.7 17248.9 10597.9 10643.4 13458.4 13722.2 13742.0 14838.4 14901.3 14911.9 14988.9 15163.8 5842.8 5893.6 5939.6 6140.4 6159.7 950.3 986.6 986.7 1007.6 1013.0 22481.4 22765.9 23417.8 28132.7 29828.9 1075.4 1090.3 1099.4 1100.2 1108.6 11924.6 11991.5 12357.9 12381.1 12445.0 213832.0 216752.0 217331.0 217508.0 218042.0 54070.0 54070.5 54525.0 54613.6 54695.0 1455.1 1455.3 1488.5 1496.9 1500.2 21962.2 22049.0 22655.0 22983.7 23149.1 47868.0 48870.8 48968.3 49077.0 49080.3 146071.0 146226.0 146520.0 146848.0 147464.3 32056.0 32708.0 32712.5 36691.4 38297.2 49158.0 49492.8 49571.0 49970.0 49990.8 33837.3 34516.8 34691.5 34936.0 34955.7 32190.0 33164.2 34101.1 34263.0 34743.0 37426.0 39493.4 39530.6 39696.8 39816.0 34440.3 35003.3 35071.5 35659.6 35721.8 24610.8 25027.6 25538.6 25858.9 26058.1 00:34:14 INFO - PID 20401 | 00:34:14 INFO - PID 20401 | [#2] jquery.html Cycles:180 Average:34810.50 Median:22376.89 stddev:79934.16 (357.2%) stddev-sans-first:80117.08 00:34:14 INFO - PID 20401 | Values: 580.3 583.9 588.4 589.4 591.6 22482.2 22487.7 23113.4 23297.4 23369.2 25622.6 26057.6 26176.0 26203.3 26314.3 6643.5 6830.8 6834.0 6836.9 6841.2 5938.3 6491.6 6511.7 6586.5 6661.4 6467.8 6506.9 6509.2 6509.4 6511.8 6439.4 6472.8 6493.4 6497.4 6501.9 6027.7 6052.0 6068.7 6073.1 6075.8 28944.0 29819.6 30393.2 30626.7 30782.7 1861.0 1895.1 1897.4 1900.7 1907.4 18965.4 19389.3 19577.7 19586.7 19597.8 2400.5 2405.7 2465.3 2466.3 2466.9 123933.0 130467.3 131335.3 131340.3 132012.0 14846.3 14981.7 15237.2 15303.3 15574.0 23791.2 23838.6 23847.0 23935.2 23982.2 10526.2 10580.9 10642.7 10657.8 10794.7 9954.7 9955.8 9976.1 10093.5 10113.2 8933.9 9135.4 9434.4 9594.1 9623.3 2873.2 2878.1 2885.1 2917.6 2979.9 908.8 932.8 941.4 958.2 959.5 19355.7 19407.4 19489.1 19525.5 19987.4 1021.6 1024.9 1031.3 1032.4 1046.0 10558.0 10645.7 11130.2 12563.2 13743.0 460720.0 470515.0 473505.0 475856.0 480498.0 121482.0 124625.4 125667.4 125794.0 128194.0 1227.1 1252.5 1253.0 1254.1 1264.7 20014.0 21241.0 21323.7 21407.3 21440.8 58652.7 59495.4 61767.1 62450.0 62911.1 74007.0 77642.0 78477.0 78769.6 78984.2 11783.2 12366.7 12369.9 14687.8 15036.2 20030.8 21045.7 21244.3 21541.6 21610.7 18136.5 18155.9 18342.9 18410.0 18432.9 14127.9 15067.5 15076.1 15227.4 15289.7 16561.4 17038.0 17355.4 17436.2 17538.6 13185.5 21176.8 22197.7 22343.8 22410.0 18099.5 18933.8 19239.8 19328.0 19342.8 00:34:14 INFO - PID 20401 | 00:34:14 INFO - PID 20401 | [#3] mootools.html Cycles:180 Average:8620.61 Median:2609.98 stddev:5946.76 (227.8%) stddev-sans-first:5938.03 00:34:14 INFO - PID 20401 | Values: 1303.3 1367.2 1391.1 1399.0 1404.7 13738.8 14331.1 14348.1 14350.9 14352.1 13678.7 14658.5 14794.3 14799.9 14817.4 2619.8 2686.8 2687.5 2688.1 2689.0 2565.9 2590.5 2701.6 2705.3 2754.7 2682.2 2716.6 2716.8 2717.4 2719.1 2584.1 2592.7 2609.6 2615.4 2620.7 2582.2 2610.4 2633.9 2634.2 2637.3 13718.5 13954.3 13966.7 14058.0 14063.3 7535.4 7806.2 7821.7 7840.7 7845.0 4501.9 4665.5 4801.1 5050.8 5060.5 1688.0 1688.7 1710.5 1729.8 1739.8 23091.2 23205.4 23311.1 23354.2 23357.9 6341.4 6427.1 6605.1 6608.8 6623.2 15381.9 15442.5 15616.8 15642.1 15666.5 7475.7 7654.3 7712.7 7727.8 7739.3 6743.8 6974.6 7127.7 7167.7 7186.9 5024.8 5158.1 5174.8 5197.7 5200.1 1366.8 1412.9 1447.6 1482.5 1668.5 823.2 859.0 861.4 865.2 886.0 7084.9 7455.9 7465.9 7603.8 7613.7 899.9 925.9 958.2 959.4 967.9 4411.4 4605.9 4643.0 4734.4 4854.5 18320.5 19377.7 19461.7 19481.8 19499.8 18315.8 19180.0 19184.2 19185.3 19309.9 10450.9 10896.5 10956.9 10957.1 11022.9 6709.0 7559.3 7612.6 7850.2 7982.4 16597.6 16678.7 16712.5 16801.5 16924.5 16014.4 16590.0 16749.4 16881.4 16929.2 11519.9 11712.9 11734.8 11753.2 11763.1 12767.7 13537.1 13590.0 13632.1 13744.3 10189.8 10410.9 10711.5 10984.9 11069.9 10397.0 10415.1 10415.9 10447.1 10697.1 8553.0 8651.4 8716.8 8728.6 8734.5 6168.8 6455.6 6542.9 6601.8 6623.7 6314.4 6478.2 6679.3 6799.6 6829.8 00:34:14 INFO - PID 20401 | 00:34:14 INFO - PID 20401 | [#4] prototype.html Cycles:180 Average:5562.18 Median:6072.18 stddev:1501.70 (24.7%) stddev-sans-first:1456.79 00:34:14 INFO - PID 20401 | Values: 486.7 495.1 521.3 527.4 531.3 6277.7 6310.5 6320.9 6326.4 6350.3 5911.6 6032.3 6067.0 6106.6 6107.9 4767.5 4808.6 4894.8 4935.4 4975.5 4849.7 4889.2 4905.0 4914.6 4919.8 4914.4 5110.9 5127.0 5153.1 5172.8 5079.4 5087.3 5140.7 5152.3 5190.5 4733.9 4748.9 4754.7 4797.2 4797.5 6029.7 6032.7 6058.8 6086.9 6095.8 5890.2 5968.9 5978.4 6020.8 6032.9 6492.1 6499.4 6521.8 6547.0 6632.5 5244.7 5249.2 5297.5 5301.3 5305.4 6858.2 6905.2 6957.5 6988.8 7003.4 5358.4 5392.0 5448.7 5461.0 5504.6 5509.0 5512.9 5533.8 5555.3 5561.3 5902.8 5943.7 6004.2 6008.6 6026.5 6449.2 6565.3 6566.5 6597.7 6640.7 1832.4 1882.6 1913.0 1925.9 1928.5 1356.4 1423.7 1455.6 1470.6 1474.3 5719.2 5744.9 5773.0 5776.2 5786.0 6521.7 6581.5 6603.4 6659.8 6681.4 5628.8 5634.1 5755.9 5756.4 5798.4 3474.5 3524.3 3534.0 3535.6 3543.8 6447.9 6551.3 6561.5 6568.8 6581.4 6808.5 6814.3 6819.9 6934.0 6940.4 6422.3 6439.3 6485.0 6534.3 6547.7 6571.3 6650.0 6705.8 6721.6 6748.9 6582.3 6628.9 6641.2 6693.4 6736.5 6511.6 6527.0 6559.9 6563.2 6564.5 4880.4 4925.6 4954.1 4954.4 5000.4 6504.5 6640.7 6659.8 6749.0 6755.0 6441.4 6499.8 6501.5 6553.9 6600.4 6366.6 6369.7 6526.5 6535.5 6551.7 6077.4 6240.1 6297.0 6337.6 6378.5 6372.4 6384.2 6468.0 6474.9 6504.6 6209.6 6219.5 6263.8 6349.8 6351.4 00:34:14 INFO - PID 20401 | 00:34:14 INFO - PID 20401 | [#5] yui.html Cycles:180 Average:22468.39 Median:18813.17 stddev:30233.91 (160.7%) stddev-sans-first:30275.27 00:34:14 INFO - PID 20401 | Values: 878.9 895.1 897.6 909.5 914.1 16572.9 16997.5 17290.4 17402.2 20608.8 19907.0 20709.4 20918.0 20938.2 20957.1 1002.9 1028.8 1030.7 1030.7 1030.8 1049.3 1050.7 1050.8 1052.2 1054.6 1030.9 1068.3 1073.4 1096.6 1104.8 1060.8 1063.9 1064.5 1065.0 1066.2 774.4 774.7 779.1 781.2 781.7 16883.2 17619.6 18087.1 18228.7 18236.3 20011.6 20300.3 20819.2 20828.4 21125.9 15650.2 15983.6 16177.4 16226.7 16235.4 3475.7 3508.7 3508.9 3511.1 3513.4 116488.0 118886.4 121967.4 124433.0 124536.0 15652.8 16090.4 16204.4 16210.3 16212.9 27285.5 29355.6 29387.2 29435.6 29469.7 8075.8 8184.9 8233.3 8692.4 8705.5 4160.2 4293.3 4293.3 4296.0 4297.2 4727.1 4827.6 4830.6 4838.0 4888.0 1031.9 1046.6 1070.3 1074.1 1075.2 654.8 673.2 689.9 698.4 703.6 10675.3 11985.8 12134.8 12241.6 12591.1 660.9 662.1 664.4 684.1 687.3 4923.6 5187.2 5201.3 5207.6 5321.9 103951.5 105258.0 105363.0 108098.0 109073.5 84814.6 86434.0 93280.5 93355.5 95477.5 72305.3 75293.0 76317.6 77759.2 78411.0 10460.4 10537.3 10836.0 11940.6 12476.4 33418.7 34837.0 36015.6 36111.0 36294.5 63842.7 64658.0 65131.3 66739.7 66931.7 17484.0 18167.4 18821.6 19023.6 19072.6 18804.7 19402.1 19416.7 19526.8 19553.1 17706.8 17899.3 17953.4 18145.6 18357.7 17971.2 18184.3 18381.6 18495.8 18831.5 17255.7 17518.6 17612.1 17975.9 18123.7 11953.5 12304.0 12951.9 13329.9 13663.5 12783.4 13203.3 13427.8 13450.6 13932.2 00:34:14 INFO - PID 20401 | -------- Summary: end -------- 00:34:14 INFO - PID 20401 | 00:34:15 INFO - TEST-INFO | 20401: exit 0 00:34:15 INFO - TEST-OK | dromaeo_css | took 1113321ms 00:34:15 INFO - TEST-START | kraken 00:34:15 INFO - Initialising browser for kraken test... 00:34:15 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:46453/getInfo.html -profile /tmp/tmpojIZ3b/profile 00:34:15 INFO - TEST-INFO | started process 20765 (/builds/slave/test/build/application/firefox/firefox http://localhost:46453/getInfo.html) 00:34:21 INFO - TEST-INFO | 20765: exit 0 00:34:21 INFO - Browser initialized. 00:34:21 INFO - Running cycle 1/1 for kraken test... 00:34:21 INFO - TEST-INFO | started process 20974 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpojIZ3b/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 00:34:22 INFO - PID 20974 | 00:34:22 INFO - PID 20974 | (/builds/slave/test/build/application/firefox/firefox:21022): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 00:34:22 INFO - PID 20974 | 00:34:32 INFO - PID 20974 | RSS: Main: 171958272 00:34:32 INFO - PID 20974 | 00:34:35 INFO - PID 20974 | Cycle 1(1): loaded http://localhost:46453/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46453/tests/kraken/driver.html?testName=audio-beat-detection) 00:34:36 INFO - PID 20974 | RSS: Main: 177893376 00:34:36 INFO - PID 20974 | 00:34:38 INFO - PID 20974 | Cycle 1(1): loaded http://localhost:46453/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46453/tests/kraken/driver.html?testName=audio-dft) 00:34:39 INFO - PID 20974 | RSS: Main: 178225152 00:34:39 INFO - PID 20974 | 00:34:42 INFO - PID 20974 | Cycle 1(1): loaded http://localhost:46453/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46453/tests/kraken/driver.html?testName=audio-fft) 00:34:43 INFO - PID 20974 | RSS: Main: 172621824 00:34:43 INFO - PID 20974 | 00:34:45 INFO - PID 20974 | Cycle 1(1): loaded http://localhost:46453/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46453/tests/kraken/driver.html?testName=audio-oscillator) 00:34:45 INFO - PID 20974 | RSS: Main: 173830144 00:34:45 INFO - PID 20974 | 00:34:47 INFO - PID 20974 | Cycle 1(1): loaded http://localhost:46453/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46453/tests/kraken/driver.html?testName=imaging-gaussian-blur) 00:34:48 INFO - PID 20974 | RSS: Main: 173080576 00:34:48 INFO - PID 20974 | 00:34:51 INFO - PID 20974 | Cycle 1(1): loaded http://localhost:46453/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46453/tests/kraken/driver.html?testName=imaging-darkroom) 00:34:52 INFO - PID 20974 | RSS: Main: 176017408 00:34:52 INFO - PID 20974 | 00:34:55 INFO - PID 20974 | Cycle 1(1): loaded http://localhost:46453/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46453/tests/kraken/driver.html?testName=imaging-desaturate) 00:34:56 INFO - PID 20974 | RSS: Main: 175853568 00:34:56 INFO - PID 20974 | 00:34:59 INFO - PID 20974 | Cycle 1(1): loaded http://localhost:46453/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46453/tests/kraken/driver.html?testName=json-parse-financial) 00:34:59 INFO - PID 20974 | RSS: Main: 176156672 00:34:59 INFO - PID 20974 | 00:35:01 INFO - PID 20974 | Cycle 1(1): loaded http://localhost:46453/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46453/tests/kraken/driver.html?testName=json-stringify-tinderbox) 00:35:02 INFO - PID 20974 | RSS: Main: 176029696 00:35:02 INFO - PID 20974 | 00:35:03 INFO - PID 20974 | Cycle 1(1): loaded http://localhost:46453/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46453/tests/kraken/driver.html?testName=stanford-crypto-aes) 00:35:04 INFO - PID 20974 | RSS: Main: 175734784 00:35:04 INFO - PID 20974 | 00:35:06 INFO - PID 20974 | Cycle 1(1): loaded http://localhost:46453/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46453/tests/kraken/driver.html?testName=stanford-crypto-ccm) 00:35:07 INFO - PID 20974 | RSS: Main: 172736512 00:35:07 INFO - PID 20974 | 00:35:10 INFO - PID 20974 | Cycle 1(1): loaded http://localhost:46453/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46453/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 00:35:10 INFO - PID 20974 | RSS: Main: 173748224 00:35:10 INFO - PID 20974 | 00:35:13 INFO - PID 20974 | Cycle 1(1): loaded http://localhost:46453/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46453/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 00:35:14 INFO - PID 20974 | RSS: Main: 173326336 00:35:14 INFO - PID 20974 | 00:35:16 INFO - PID 20974 | Cycle 1(1): loaded http://localhost:46453/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46453/tests/kraken/driver.html?testName=ai-astar) 00:35:16 INFO - PID 20974 | RSS: Main: 175157248 00:35:16 INFO - PID 20974 | 00:35:16 INFO - PID 20974 | __start_tp_report 00:35:16 INFO - PID 20974 | _x_x_mozilla_page_load 00:35:16 INFO - PID 20974 | _x_x_mozilla_page_load_details 00:35:16 INFO - PID 20974 | |i|pagename|runs| 00:35:16 INFO - PID 20974 | |0;ai-astar;102;109;103;108;109;113;109;100;109;110 00:35:16 INFO - PID 20974 | |1;audio-beat-detection;148;119;151;171;117;157;147;122;177;118 00:35:16 INFO - PID 20974 | |2;audio-dft;164;165;163;163;162;165;163;164;171;165 00:35:16 INFO - PID 20974 | |3;audio-fft;75;75;76;170;77;75;75;75;153;76 00:35:16 INFO - PID 20974 | |4;audio-oscillator;79;79;131;80;79;80;139;79;80;129 00:35:16 INFO - PID 20974 | |5;imaging-gaussian-blur;115;115;114;114;115;114;114;114;114;114 00:35:16 INFO - PID 20974 | |6;imaging-darkroom;151;151;151;152;152;151;151;151;151;153 00:35:16 INFO - PID 20974 | |7;imaging-desaturate;94;93;94;93;94;95;94;95;94;95 00:35:16 INFO - PID 20974 | |8;json-parse-financial;56;55;56;56;56;56;56;56;56;56 00:35:16 INFO - PID 20974 | |9;json-stringify-tinderbox;54;60;54;54;56;54;54;54;54;55 00:35:16 INFO - PID 20974 | |10;stanford-crypto-aes;80;81;82;91;83;80;79;99;80;81 00:35:16 INFO - PID 20974 | |11;stanford-crypto-ccm;130;131;133;141;147;130;137;140;128;174 00:35:16 INFO - PID 20974 | |12;stanford-crypto-pbkdf2;195;190;184;181;194;181;186;181;186;182 00:35:16 INFO - PID 20974 | |13;stanford-crypto-sha256-iterative;68;68;71;68;67;71;68;69;69;66 00:35:16 INFO - PID 20974 | __end_tp_report 00:35:16 INFO - PID 20974 | __start_cc_report 00:35:16 INFO - PID 20974 | _x_x_mozilla_cycle_collect,983 00:35:16 INFO - PID 20974 | __end_cc_report 00:35:16 INFO - PID 20974 | __startTimestamp1501659316620__endTimestamp 00:35:16 INFO - PID 20974 | 00:35:16 INFO - PID 20974 | ------- Summary: start ------- 00:35:16 INFO - PID 20974 | Number of tests: 14 00:35:16 INFO - PID 20974 | 00:35:16 INFO - PID 20974 | [#0] ai-astar Cycles:10 Average:107.20 Median:109.00 stddev:4.10 (3.8%) stddev-sans-first:3.90 00:35:16 INFO - PID 20974 | Values: 102.0 109.0 103.0 108.0 109.0 113.0 109.0 100.0 109.0 110.0 00:35:16 INFO - PID 20974 | 00:35:16 INFO - PID 20974 | [#1] audio-beat-detection Cycles:10 Average:142.70 Median:149.50 stddev:22.50 (15.1%) stddev-sans-first:23.79 00:35:16 INFO - PID 20974 | Values: 148.0 119.0 151.0 171.0 117.0 157.0 147.0 122.0 177.0 118.0 00:35:16 INFO - PID 20974 | 00:35:16 INFO - PID 20974 | [#2] audio-dft Cycles:10 Average:164.50 Median:164.50 stddev:2.51 (1.5%) stddev-sans-first:2.65 00:35:16 INFO - PID 20974 | Values: 164.0 165.0 163.0 163.0 162.0 165.0 163.0 164.0 171.0 165.0 00:35:16 INFO - PID 20974 | 00:35:16 INFO - PID 20974 | [#3] audio-fft Cycles:10 Average:92.70 Median:75.00 stddev:36.49 (48.7%) stddev-sans-first:38.13 00:35:16 INFO - PID 20974 | Values: 75.0 75.0 76.0 170.0 77.0 75.0 75.0 75.0 153.0 76.0 00:35:16 INFO - PID 20974 | 00:35:16 INFO - PID 20974 | [#4] audio-oscillator Cycles:10 Average:95.50 Median:79.00 stddev:26.00 (32.9%) stddev-sans-first:26.88 00:35:16 INFO - PID 20974 | Values: 79.0 79.0 131.0 80.0 79.0 80.0 139.0 79.0 80.0 129.0 00:35:16 INFO - PID 20974 | 00:35:16 INFO - PID 20974 | [#5] imaging-gaussian-blur Cycles:10 Average:114.30 Median:114.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 00:35:16 INFO - PID 20974 | Values: 115.0 115.0 114.0 114.0 115.0 114.0 114.0 114.0 114.0 114.0 00:35:16 INFO - PID 20974 | 00:35:16 INFO - PID 20974 | [#6] imaging-darkroom Cycles:10 Average:151.40 Median:151.00 stddev:0.70 (0.5%) stddev-sans-first:0.73 00:35:16 INFO - PID 20974 | Values: 151.0 151.0 151.0 152.0 152.0 151.0 151.0 151.0 151.0 153.0 00:35:16 INFO - PID 20974 | 00:35:16 INFO - PID 20974 | [#7] imaging-desaturate Cycles:10 Average:94.10 Median:94.00 stddev:0.74 (0.8%) stddev-sans-first:0.78 00:35:16 INFO - PID 20974 | Values: 94.0 93.0 94.0 93.0 94.0 95.0 94.0 95.0 94.0 95.0 00:35:16 INFO - PID 20974 | 00:35:16 INFO - PID 20974 | [#8] json-parse-financial Cycles:10 Average:55.90 Median:56.00 stddev:0.32 (0.6%) stddev-sans-first:0.33 00:35:16 INFO - PID 20974 | Values: 56.0 55.0 56.0 56.0 56.0 56.0 56.0 56.0 56.0 56.0 00:35:16 INFO - PID 20974 | 00:35:16 INFO - PID 20974 | [#9] json-stringify-tinderbox Cycles:10 Average:54.90 Median:54.00 stddev:1.91 (3.5%) stddev-sans-first:2.00 00:35:16 INFO - PID 20974 | Values: 54.0 60.0 54.0 54.0 56.0 54.0 54.0 54.0 54.0 55.0 00:35:16 INFO - PID 20974 | 00:35:16 INFO - PID 20974 | [#10] stanford-crypto-aes Cycles:10 Average:83.60 Median:81.50 stddev:6.40 (7.9%) stddev-sans-first:6.65 00:35:16 INFO - PID 20974 | Values: 80.0 81.0 82.0 91.0 83.0 80.0 79.0 99.0 80.0 81.0 00:35:16 INFO - PID 20974 | 00:35:16 INFO - PID 20974 | [#11] stanford-crypto-ccm Cycles:10 Average:139.10 Median:138.50 stddev:13.67 (9.9%) stddev-sans-first:14.09 00:35:16 INFO - PID 20974 | Values: 130.0 131.0 133.0 141.0 147.0 130.0 137.0 140.0 128.0 174.0 00:35:16 INFO - PID 20974 | 00:35:16 INFO - PID 20974 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.00 Median:186.00 stddev:5.33 (2.9%) stddev-sans-first:4.56 00:35:16 INFO - PID 20974 | Values: 195.0 190.0 184.0 181.0 194.0 181.0 186.0 181.0 186.0 182.0 00:35:16 INFO - PID 20974 | 00:35:16 INFO - PID 20974 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.50 Median:68.50 stddev:1.58 (2.3%) stddev-sans-first:1.67 00:35:16 INFO - PID 20974 | Values: 68.0 68.0 71.0 68.0 67.0 71.0 68.0 69.0 69.0 66.0 00:35:16 INFO - PID 20974 | -------- Summary: end -------- 00:35:16 INFO - PID 20974 | 00:35:17 INFO - PID 20974 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 00:35:17 INFO - TEST-INFO | 20974: exit 0 00:35:17 INFO - TEST-OK | kraken | took 61967ms 00:35:17 INFO - SUITE-END | took 1175s 00:35:17 INFO - Completed test suite (00:19:35) 00:35:17 INFO - javascript benchmark 00:35:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8469.504662497518, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3118.485449900768, 3132.216945763559, 3150.1494596458942, 3157.510585859193, 3158.210447388153, 6170.876582468348, 6191.62850228986, 6195.566310358271, 6233.345498722637, 6259.280182778982, 6101.359357267478, 6180.783672571461, 6200.596961197519, 6208.317085120633, 6296.905546416808, 5650.180723795047, 5711.400302968181, 5728.369879313273, 5732.455416735404, 5786.016377215862, 5689.374168841424, 5745.885082298351, 5785.797497087603, 5793.275840519935, 5819.447152520504, 5715.714214289269, 5731.627444216113, 5776.046952252873, 5786.56600754943, 5836.2121113649755, 5494.780208791643, 5657.632253903483, 5661.433856614306, 5783.913241301384, 5818.650880947135, 5651.717414129318, 5682.431756824326, 5715.942840571583, 5724.341700704389, 5737.856553586152, 6104.572679912408, 6116.571839971204, 6133.44798968095, 6137.171481849938, 6181.9690901545655, 6653.800385988428, 6690.933090669079, 6715.2277488089, 6721.260661327299, 6761.628110453923, 6702.631355275458, 6769.289224631441, 6798.354156355188, 6799.762008329685, 6809.216940051908, 6189.319174890758, 6223.439890409885, 6233.906491402588, 6288.968555157195, 6317.305096439388, 6696.0, 6763.830904227434, 6843.0, 6894.103766510321, 6898.793036208922, 6592.143021407186, 6607.141071660653, 6617.503687223478, 6656.83357916056, 6662.467002639777, 6672.799816005528, 6685.565438246498, 6743.2919543448215, 6770.424513916372, 6779.35596118368, 6602.966985165043, 6634.203895532471, 6634.800955971328, 6727.798166055026, 6745.460363170934, 6478.740850365933, 6590.209174899042, 6598.571092878948, 6606.570572912745, 6609.6364699941005, 5286.947130528645, 5292.947070529245, 5294.814681486129, 5294.841154765286, 5297.311349524537, 4547.818087276538, 4585.403897493371, 4601.930971035437, 4604.700694454849, 4613.215753321943, 5714.142878568165, 5721.370649228582, 5722.313322401338, 5724.198612194296, 5729.312482502127, 6589.670516474157, 6605.976073708487, 6607.141071660653, 6619.304972977864, 6619.371159739817, 5654.74553645087, 5720.971395143081, 5795.94204057954, 5799.159121927381, 5892.086726557305, 5850.063989761617, 5918.970405147947, 5929.080992446092, 5939.406059394026, 5943.10853371989, 6337.04944258365, 6434.549581529248, 6456.677166141674, 6531.216254049449, 6581.078648989145, 6646.335366463315, 6658.5671931324305, 6667.833304167435, 6668.899966500409, 6675.566088204251, 5566.137248726453, 6442.226932768098, 6467.805965821033, 6470.964645656861, 6517.967410162919, 6582.736690532325, 6605.5376123670885, 6671.866562668816, 6672.299408562225, 6692.163479565054, 6499.155109835691, 6624.867502649824, 6647.60114393138, 6783.728650854108, 6789.592624442549, 6456.838579035561, 6459.547831651739, 6471.482281417381, 6472.190976127984, 6495.772647957298, 6414.518911081781, 6445.452136568444, 6459.9031014533875, 6502.902456463252, 6531.3468653134305, 6388.8083357499345, 6397.552171347961, 6461.57999730016, 6470.28826829039, 6503.349665033459, 6402.263739669997, 6481.0, 6492.35076492347, 6640.070390145287, 6640.501962352746, 6304.464120549805, 6314.810555683337, 6397.200349956255, 6421.967890160519, 6424.486041116793], "value": 6078.991212929021, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [839.5340585974782, 888.4935586715573, 902.3593248793356, 917.7292698653898, 1161.622472696374, 31577.368452630963, 32148.0, 41826.0, 42311.36532952008, 42482.787586062106, 42772.358414623806, 42810.57189428105, 42898.14203715932, 43158.136837263235, 43249.13501729971, 7139.107611548557, 7584.544927304351, 7748.767536973872, 7750.186230445806, 7793.103793063785, 7583.431242656795, 7700.460967732233, 7717.884231736529, 7718.382529397635, 7718.614069296541, 7688.884666730003, 7702.4993375430695, 7726.8454630907345, 7732.033495813023, 7748.457607967444, 7677.6161191940455, 7682.193369696157, 7700.075990881074, 7710.730124445618, 7715.845683086335, 7595.848083038363, 7669.156392796844, 7670.194629563871, 7671.808204794925, 7686.039245094364, 38224.61775382266, 38744.61255387438, 38940.80529597334, 39019.0, 39076.609233907584, 3473.9131521711906, 3500.8074555899416, 3502.159481724393, 3502.7548071634997, 3504.0, 32309.67690323114, 32431.351372972407, 32520.512192317135, 32531.349373012643, 32766.0, 4816.157172494799, 4863.221884498499, 4891.1195984722735, 4910.165271903785, 4912.435069966964, 183554.0, 183661.0, 184162.99999999866, 184255.0, 184335.0, 49401.75299123481, 49595.0, 49643.75178124086, 50073.0, 50392.74803625958, 53105.46894531005, 53131.468685313426, 53136.202956955676, 53139.46860531423, 53195.0, 16461.176941153135, 16625.584360390843, 17068.658626827393, 17093.743593846102, 17248.913755431142, 10597.89402105969, 10643.361398316125, 13458.394372253271, 13722.245276509888, 13742.0, 14838.406463741545, 14901.254937253094, 14911.925440372946, 14988.925055374653, 15163.848361516244, 5842.824715258549, 5893.574096638266, 5939.643621382731, 6140.416660417253, 6159.6612186329285, 950.2777888804559, 986.5659109991581, 986.7286496213599, 1007.5717819926502, 1012.9848052279222, 22481.437964050703, 22765.88617056937, 23417.7658223419, 28132.71867281342, 29828.850855745583, 1075.4138994248153, 1090.2695194219891, 1099.4008265495324, 1100.2023532938715, 1108.562117963416, 11924.642260732191, 11991.520339186509, 12357.93821030889, 12381.133320667466, 12445.001891337652, 213832.0, 216752.0, 217331.0, 217508.0, 218042.0, 54070.0, 54070.45929540733, 54525.0, 54613.63465913384, 54695.0, 1455.1487379882676, 1455.316001479303, 1488.4790323386944, 1496.91018538888, 1500.189897455362, 21962.215113954546, 22049.0, 22655.0, 22983.65524517167, 23149.074037039005, 47868.0, 48870.75564622154, 48968.26547601718, 49077.0, 49080.263796042374, 146071.0, 146226.0, 146520.0, 146848.0, 147464.26267868592, 32056.0, 32708.0, 32712.509312359856, 36691.44962825506, 38297.23405531929, 49158.0, 49492.75253623853, 49571.0, 49970.0, 49990.75004624954, 33837.32325353529, 34516.827415863765, 34691.4796278051, 34936.0, 34955.65044349524, 32190.0, 33164.170895726835, 34101.147471313416, 34263.0, 34743.0, 37426.0, 39493.40759888547, 39530.60469395269, 39696.801515992236, 39816.0, 34440.311193776484, 35003.29993400168, 35071.47392789059, 35659.643403565635, 35721.82139089288, 24610.753892461562, 25027.624585630867, 25538.59537725443, 25858.870705647103, 26058.087966921066], "value": 17477.082395908892, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [580.2659635561015, 583.883223355329, 588.4233451217804, 589.3634874335718, 591.6272748168653, 22482.213122540714, 22487.662685059717, 23113.422164445878, 23297.417564560852, 23369.18207862723, 25622.615660765103, 26057.60913586293, 26176.0, 26203.3449163771, 26314.342141446425, 6643.468522518199, 6830.795076147699, 6833.965830170842, 6836.897446538306, 6841.213260475034, 5938.346781853993, 6491.642959637218, 6511.739530418778, 6586.47308215344, 6661.400473957343, 6467.83830404239, 6506.869862602745, 6509.218893732758, 6509.4466970307585, 6511.772087976922, 6439.356064393546, 6472.805815825485, 6493.383128602775, 6497.447716944065, 6501.902471462932, 6027.7287522061615, 6052.031674932034, 6068.6662233577135, 6073.119397687311, 6075.756969721206, 28944.0, 29819.552706709415, 30393.24016899573, 30626.693733062606, 30782.692173078205, 1861.0, 1895.0524737631185, 1897.3548993342192, 1900.6863867461852, 1907.3705677126654, 18965.43103706891, 19389.321373751853, 19577.706334404997, 19586.70619940702, 19597.80402195974, 2400.538103186282, 2405.7353691093967, 2465.29739024379, 2466.3464181991785, 2466.90132394704, 123933.0, 130467.34766326203, 131335.34332328278, 131340.3432982829, 132012.0, 14846.331915063847, 14981.70036599262, 15237.238138093051, 15303.311350989232, 15574.0, 23791.167309144097, 23838.642420363707, 23847.0, 23935.16226932049, 23982.160624378062, 10526.210534209966, 10580.89419105799, 10642.680719578426, 10657.84013239802, 10794.73013174677, 9954.651587194558, 9955.751106222402, 9976.102150806462, 10093.545790439448, 10113.241506887012, 8933.865992010125, 9135.40619859707, 9434.367768409074, 9594.136527712539, 9623.326367154232, 2873.161176846778, 2878.136559032324, 2885.1200383882697, 2917.595877049855, 2979.8957036503616, 908.7818923458323, 932.7574830544107, 941.4069136444133, 958.1903291718487, 959.5154447004358, 19355.709664354763, 19407.417777466697, 19489.122989465508, 19525.511862203275, 19987.400377988684, 1021.5862575656871, 1024.8667673202435, 1031.2677998620914, 1032.3650954662978, 1045.994770026145, 10558.0, 10645.680629581126, 11130.220884538005, 12563.18339307942, 13743.03798734099, 460720.0, 470515.0, 473505.0, 475856.0, 480498.0, 121482.0, 124625.37687311505, 125667.37166314293, 125794.0, 128194.0, 1227.1042139238227, 1252.4990003998291, 1252.9686757831128, 1254.0657210378145, 1264.6901509130505, 20014.0, 21241.0, 21323.727780497848, 21407.250746223806, 21440.78559214388, 58652.70673646605, 59495.40504594899, 61767.07349389673, 62450.0, 62911.11266662007, 74007.0, 77642.0, 78477.0, 78769.60615196887, 78984.21015789769, 11783.175177737476, 12366.690832729253, 12369.93815030955, 14687.779683304545, 15036.173010484528, 20030.799692003475, 21045.684314735598, 21244.25645102414, 21541.569168616854, 21610.675839862728, 18136.546586335448, 18155.909220453814, 18342.90828545849, 18410.0, 18432.907835460737, 14127.858721412655, 15067.547973560811, 15076.095434273979, 15227.390904364145, 15289.694206115593, 16561.42035028768, 17038.0, 17355.3925612608, 17436.215370308113, 17538.561535961704, 13185.53850615224, 21176.78823211748, 22197.667034994163, 22343.77656223417, 22410.0, 18099.54751131232, 18933.810661893756, 19239.807601923803, 19328.0, 19342.806571934663], "value": 12178.05962045331, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1303.341812384746, 1367.234348764692, 1391.1096897985292, 1398.9720205595881, 1404.705011947491, 13738.793918091224, 14331.1401315921, 14348.06737562059, 14350.928245358784, 14352.138871667707, 13678.658033549165, 14658.48695295665, 14794.334254958525, 14799.852001479981, 14817.407303707838, 2619.790416766664, 2686.8387896726163, 2687.4625074984983, 2688.1397952655157, 2689.031948498549, 2565.9358516037064, 2590.5466543354837, 2701.6217729517884, 2705.296622878046, 2754.669439667233, 2682.222155574881, 2716.633254510645, 2716.7962402819767, 2717.415755612543, 2719.075514325129, 2584.0697348954363, 2592.7277635848245, 2609.556375416184, 2615.3853844346563, 2620.737926207383, 2582.21242521031, 2610.3996080901497, 2633.868306584663, 2634.2492389669046, 2637.274749443918, 13718.519851805138, 13954.302284885816, 13966.720665586632, 14058.0, 14063.2968351582, 7535.397168226528, 7806.180351063113, 7821.687132514692, 7840.6863725451485, 7845.0, 4501.887452813673, 4665.463471700764, 4801.135795556798, 5050.823221187277, 5060.493950604947, 1687.9831201687948, 1688.653825965674, 1710.529604358799, 1729.80107287661, 1739.7912250530017, 23091.19180828663, 23205.41986450352, 23311.067557297687, 23354.18260360879, 23357.88321058401, 6341.352611203301, 6427.100205971168, 6605.1083103780375, 6608.801735947929, 6623.20521537409, 15381.9230903847, 15442.45951391696, 15616.76574851378, 15642.139682317355, 15666.530004099895, 7475.70097196117, 7654.349380302625, 7712.691492340356, 7727.806804829812, 7739.264769846855, 6743.831404214836, 6974.616396098263, 7127.7148914042855, 7167.749128780468, 7186.856262874818, 5024.773885175176, 5158.123119069767, 5174.7930082796265, 5197.66215196011, 5200.141976573898, 1366.760816857066, 1412.9364178611986, 1447.5874375802846, 1482.495951376537, 1668.4744305543716, 823.197382552007, 858.9785255368665, 861.371199024703, 865.2472349056361, 885.9645614175361, 7084.92915070853, 7455.925440745632, 7465.888011679829, 7603.809904752426, 7613.6573854176695, 899.9415038022507, 925.9305552083621, 958.2382006305004, 959.3716115944068, 967.9225661947028, 4411.404460397805, 4605.884852878705, 4643.0, 4734.360861283682, 4854.538818812207, 18320.45038648843, 19377.709334359995, 19461.708074378897, 19481.805181948, 19499.8050019498, 18315.816841831413, 19180.0, 19184.232630694616, 19185.328513502238, 19309.903450482656, 10450.895491044992, 10896.455177241107, 10956.945215273872, 10957.12343012573, 11022.889771102347, 6708.966455167693, 7559.3196612305155, 7612.619369031637, 7850.214978502333, 7982.441229113906, 16597.58506037359, 16678.66642667164, 16712.49862504127, 16801.495955121365, 16924.492265232064, 16014.359425622844, 16590.004599724056, 16749.413770517975, 16881.40915067967, 16929.23818428149, 11519.884801152217, 11712.88287117118, 11734.765304694029, 11753.236039657395, 11763.117766167743, 12767.680807979503, 13537.1200871943, 13590.0, 13632.113912595649, 13744.312784360542, 10189.84715229287, 10410.895891040993, 10711.517981690999, 10984.945075274893, 11069.944650276697, 10397.0, 10415.114715249289, 10415.94792026035, 10447.05976462122, 10697.14422846155, 8553.0, 8651.351148663749, 8716.825663486821, 8728.56357182151, 8734.519601421984, 6168.814935551941, 6455.61266324022, 6542.934570654233, 6601.768938087144, 6623.735050597922, 6314.400131987454, 6478.190226221723, 6679.298673639198, 6799.626020568916, 6829.838927382456], "value": 6267.860862471458, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [486.67149673970073, 495.11868873405353, 521.2754271562527, 527.3618921105463, 531.3225637312429, 6277.717502712378, 6310.4636105930995, 6320.8938435773725, 6326.367363263671, 6350.301466838644, 5911.556633252501, 6032.336442991278, 6066.968615335392, 6106.572539922207, 6107.877842443148, 4767.522068158882, 4808.567228949394, 4894.8286809961655, 4935.383077115361, 4975.477574854643, 4849.733264670442, 4889.242167464049, 4904.969956309179, 4914.576880807266, 4919.7540122993705, 4914.387924875214, 5110.8756073663735, 5126.974365128169, 5153.123968925292, 5172.818951336704, 5079.390473143209, 5087.3386459759995, 5140.7172605506685, 5152.252923326097, 5190.506901844319, 4733.881652958669, 4748.928766068511, 4754.7147171169845, 4797.160496913027, 4797.472278049447, 6029.728662210211, 6032.707571122989, 6058.757649694007, 6086.908696369558, 6095.817125486243, 5890.175375447441, 5968.850778730566, 5978.40215978403, 6020.759169633209, 6032.909506357408, 6492.058651495507, 6499.415052645238, 6521.836954076139, 6546.967265163691, 6632.502562307846, 5244.685318880879, 5249.212618107276, 5297.549708274802, 5301.257823904656, 5305.44292849253, 6858.245592984817, 6905.205901321312, 6957.5129740918155, 6988.79033628992, 7003.439724822002, 5358.356997160366, 5391.973040134774, 5448.727563621803, 5461.044317244467, 5504.614676972654, 5509.0, 5512.944870551243, 5533.833984980457, 5555.30558680165, 5561.304836895388, 5902.793402230901, 5943.732532036069, 6004.189434426384, 6008.579399441997, 6026.487748541335, 6449.193850768654, 6565.343465653396, 6566.474682025522, 6597.73609055642, 6640.701168447432, 1832.4044685477006, 1882.6140641168527, 1912.990435047816, 1925.9229630814611, 1928.517870532367, 1356.382845805176, 1423.7081398313321, 1455.5997100797154, 1470.624965657114, 1474.2849717886816, 5719.19931209631, 5744.942550574524, 5773.0, 5776.249087618583, 5785.971070144707, 6521.706523206467, 6581.473482121419, 6603.405693487609, 6659.766908158192, 6681.42986398196, 5628.831135065954, 5634.098544232903, 5755.913661295084, 5756.366799652035, 5798.362180160263, 3474.513568100468, 3524.3303772283184, 3533.9823300883845, 3535.646435356444, 3543.805090720035, 6447.935520644827, 6551.344865513506, 6561.475081993429, 6568.807156163993, 6581.407673309425, 6808.480862081062, 6814.2623630973785, 6819.942908849136, 6933.965330173317, 6940.444764418834, 6422.2614399344675, 6439.259485159173, 6484.967575162094, 6534.346565343428, 6547.705353259114, 6571.277159512354, 6650.002499625095, 6705.765298214539, 6721.596704197764, 6748.865022699617, 6582.341765823379, 6628.867422651423, 6641.1927774032, 6693.364130407604, 6736.4947628929, 6511.576747511396, 6526.967365163144, 6559.934400656123, 6563.245226798979, 6564.4748420127235, 4880.365552478155, 4925.556699897026, 4954.1330267202375, 4954.380702412199, 5000.449950505515, 6504.512161587994, 6640.701168447528, 6659.768977392434, 6748.966255168692, 6754.966225168842, 6441.355864413521, 6499.772507962199, 6501.544891857714, 6553.934460655523, 6600.372964568358, 6366.6180029198395, 6369.681515924278, 6526.477881769352, 6535.509836762167, 6551.672416379067, 6077.392260773887, 6240.063990401476, 6297.04791514632, 6337.588056776295, 6378.48972082223, 6372.36276372359, 6384.201974753156, 6467.967660161858, 6474.902876456951, 6504.642244676399, 6209.565330426827, 6219.500621850087, 6263.78076767311, 6349.777757778455, 6351.364863513611], "value": 5132.02065176703, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [878.8725634782957, 895.1004240738057, 897.560195504203, 909.4952301472682, 914.1178762494192, 16572.917135414322, 16997.49007529776, 17290.394836180752, 17402.216900239488, 20608.793912060875, 19907.0, 20709.378718638465, 20918.0, 20938.16247350108, 20957.056932438038, 1002.9168498022137, 1028.7582418131733, 1030.6701855406272, 1030.7423144213947, 1030.7577719235974, 1049.2812423489895, 1050.7058023753323, 1050.8056009638242, 1052.163529993657, 1054.5834395413845, 1030.9432981186033, 1068.3322923173016, 1073.4471747050275, 1096.5504143301248, 1104.7624760676454, 1060.777236780277, 1063.930844495109, 1064.5209655654908, 1064.973375665607, 1066.248294952057, 774.423054824154, 774.6901239504188, 779.1468342165376, 781.1602527283193, 781.6677911887482, 16883.15584220796, 17619.559511012198, 18087.095645217687, 18228.726569101473, 18236.270549178018, 20011.599768004704, 20300.289489867784, 20819.16723331065, 20828.375148745563, 21125.894370528204, 15650.217489125613, 15983.600409989727, 16177.433789817418, 16226.675466490722, 16235.350585976546, 3475.7045651119433, 3508.736844736655, 3508.947365789515, 3511.052015955703, 3513.402721537345, 116488.0, 118886.40556797161, 121967.39016304951, 124432.9999999991, 124536.0, 15652.843471565138, 16090.436834710963, 16204.43284485061, 16210.270537825827, 16212.918935405247, 27285.45429091447, 29355.55966660502, 29387.23676579412, 29435.558466623017, 29469.705302947124, 8075.798105047304, 8184.877226841602, 8233.300169485541, 8692.434991725517, 8705.521196334263, 4160.18876319121, 4293.313069908862, 4293.334533147367, 4296.033392486665, 4297.205017071852, 4727.101850648366, 4827.589654879304, 4830.565249127595, 4837.975810120997, 4888.046830868017, 1031.886492485826, 1046.6493724602344, 1070.2936062198912, 1074.065562960214, 1075.2419544221304, 654.8395643067479, 673.2157037051878, 689.899964505144, 698.3889097040089, 703.5532436902604, 10675.306105103144, 11985.760284794254, 12134.817977730341, 12241.571544995883, 12591.055670824542, 660.9173853268342, 662.0797092042066, 664.3954001858285, 684.1106561470069, 687.3195536418909, 4923.630727695438, 5187.221916712523, 5201.349831271091, 5207.635465517453, 5321.946780532223, 103951.4802425983, 105258.0, 105363.0, 108098.0, 109073.45463272791, 84814.5759271212, 86434.0, 93280.53359733293, 93355.53322233346, 95477.5226123865, 72305.2769472309, 75293.0, 76317.6184119087, 77759.2224077752, 78411.0, 10460.372377657364, 10537.315074520131, 10836.0, 11940.582079627171, 12476.376181191086, 33418.66581334253, 34837.0, 36015.63984360123, 36111.0, 36294.455583166804, 63842.68078659763, 64658.0, 65131.34868651442, 66739.66630166819, 66931.66534167298, 17484.0, 18167.36414225496, 18821.623567528848, 19023.619527609648, 19072.618547628692, 18804.7179292308, 19402.12690428906, 19416.70874936849, 19526.8047319525, 19553.120109594816, 17706.82293177052, 17899.284028638707, 17953.371631992817, 18145.637087257917, 18357.72463413023, 17971.191296391957, 18184.27262909469, 18381.632367352846, 18495.815041849946, 18831.529211769815, 17255.654886902445, 17518.562035949202, 17612.119394030502, 17975.910120449837, 18123.72814407811, 11953.521859125538, 12304.0, 12951.870481295067, 13329.866701332863, 13663.521776737767, 12783.360831958178, 13203.273819939996, 13427.798583021458, 13450.596482105553, 13932.164070155823], "value": 8868.18231289854, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1550.3999999999996, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 109.0, 103.0, 108.0, 109.0, 113.0, 109.0, 100.0, 109.0, 110.0], "value": 107.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 119.0, 151.0, 171.0, 117.0, 157.0, 147.0, 122.0, 177.0, 118.0], "value": 142.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 165.0, 163.0, 163.0, 162.0, 165.0, 163.0, 164.0, 171.0, 165.0], "value": 164.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 76.0, 170.0, 77.0, 75.0, 75.0, 75.0, 153.0, 76.0], "value": 92.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 131.0, 80.0, 79.0, 80.0, 139.0, 79.0, 80.0, 129.0], "value": 95.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 115.0, 114.0, 114.0, 115.0, 114.0, 114.0, 114.0, 114.0, 114.0], "value": 114.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 151.0, 151.0, 152.0, 152.0, 151.0, 151.0, 151.0, 151.0, 153.0], "value": 151.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 94.0, 93.0, 94.0, 95.0, 94.0, 95.0, 94.0, 95.0], "value": 94.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 56.0, 56.0, 56.0, 56.0, 56.0, 56.0, 56.0, 56.0], "value": 55.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 60.0, 54.0, 54.0, 56.0, 54.0, 54.0, 54.0, 54.0, 55.0], "value": 54.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 81.0, 82.0, 91.0, 83.0, 80.0, 79.0, 99.0, 80.0, 81.0], "value": 83.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 131.0, 133.0, 141.0, 147.0, 130.0, 137.0, 140.0, 128.0, 174.0], "value": 139.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 190.0, 184.0, 181.0, 194.0, 181.0, 186.0, 181.0, 186.0, 182.0], "value": 186.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 71.0, 68.0, 67.0, 71.0, 68.0, 69.0, 69.0, 66.0], "value": 68.5, "unit": "score"}]}]} 00:35:17 INFO - Return code: 0 00:35:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 00:35:17 INFO - # TBPL SUCCESS # 00:35:17 INFO - Running post-action listener: _package_coverage_data 00:35:17 INFO - Running post-action listener: _resource_record_post_action 00:35:17 INFO - [mozharness: 2017-08-02 07:35:17.891135Z] Finished run-tests step (success) 00:35:17 INFO - Running post-run listener: _resource_record_post_run 00:35:17 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 4096; Write bytes: 432447488; Read time: 8; Write time: 463248 00:35:17 INFO - TinderboxPrint: CPU usage
13.1% 00:35:17 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 00:35:17 INFO - TinderboxPrint: I/O write bytes / time
432,447,488 / 463,248 00:35:17 INFO - TinderboxPrint: CPU idle
8,268.0 (86.8%) 00:35:17 INFO - TinderboxPrint: CPU user
1,202.3 (12.6%) 00:35:17 INFO - TinderboxPrint: Swap in / out
0 / 0 00:35:17 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 107057152; Read time: 0; Write time: 119964 00:35:17 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 00:35:17 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 0; Write bytes: 324775936; Read time: 0; Write time: 343284 00:35:18 INFO - Running post-run listener: _upload_blobber_files 00:35:18 INFO - Blob upload gear active. 00:35:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 00:35:18 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 00:35:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 00:35:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 00:35:18 INFO - (blobuploader) - INFO - Open directory for files ... 00:35:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 00:35:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:35:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:35:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 00:35:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:35:19 INFO - (blobuploader) - INFO - Done attempting. 00:35:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 00:35:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:35:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:35:20 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 00:35:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:35:20 INFO - (blobuploader) - INFO - Done attempting. 00:35:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 00:35:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:35:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:35:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 00:35:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:35:21 INFO - (blobuploader) - INFO - Done attempting. 00:35:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 00:35:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:35:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:35:28 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 00:35:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:35:28 INFO - (blobuploader) - INFO - Done attempting. 00:35:28 INFO - (blobuploader) - INFO - Iteration through files over. 00:35:28 INFO - Return code: 0 00:35:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 00:35:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 00:35:28 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/da0cdfab7225c4cc890e8f4f1edb7b3e36bac2e31e58be645b05b63de8ae605b509ba2c976675ed45e29cdab7b670a5b285a334f3c3ad44d396eb3e910abba37", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/570e43f90e181b2e87b3bb93b56e19ff6cd6d82284abe6da6076b065a12f48e5a88ff9ff6d5efb26ae5ea55199e1583d218ecae9c634c81497c21fee019791c2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9ded2dd60f65f26df5de63163fe14bad559a96e6e1918879295d09654cf73eda7267bb9df3721696e2ebee241f8e80bf4d5155f7e7ea332bf465738b78cb5c20", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/23d654692365c65084807fd0686957d2f251b9cbaa3a0f621ebdeb89b516ddfe3e9430f3730beb105148492fa66e9bd596cf073d7185bc81506266ae5c4c8f0e"} 00:35:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 00:35:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 00:35:28 INFO - Contents: 00:35:28 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/da0cdfab7225c4cc890e8f4f1edb7b3e36bac2e31e58be645b05b63de8ae605b509ba2c976675ed45e29cdab7b670a5b285a334f3c3ad44d396eb3e910abba37", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/570e43f90e181b2e87b3bb93b56e19ff6cd6d82284abe6da6076b065a12f48e5a88ff9ff6d5efb26ae5ea55199e1583d218ecae9c634c81497c21fee019791c2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9ded2dd60f65f26df5de63163fe14bad559a96e6e1918879295d09654cf73eda7267bb9df3721696e2ebee241f8e80bf4d5155f7e7ea332bf465738b78cb5c20", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/23d654692365c65084807fd0686957d2f251b9cbaa3a0f621ebdeb89b516ddfe3e9430f3730beb105148492fa66e9bd596cf073d7185bc81506266ae5c4c8f0e"} 00:35:28 INFO - Running post-run listener: copy_logs_to_upload_dir 00:35:28 INFO - Copying logs to upload dir... 00:35:28 INFO - mkdir: /builds/slave/test/build/upload/logs 00:35:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1256.197557 ========= master_lag: -0.11 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2017-08-02 00:35:27.837856) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-02 00:35:27.845749) ========= 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=c6927cf182745ad25b1d38b9000001fe-1501645127.809833-2090461881 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/da0cdfab7225c4cc890e8f4f1edb7b3e36bac2e31e58be645b05b63de8ae605b509ba2c976675ed45e29cdab7b670a5b285a334f3c3ad44d396eb3e910abba37", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/570e43f90e181b2e87b3bb93b56e19ff6cd6d82284abe6da6076b065a12f48e5a88ff9ff6d5efb26ae5ea55199e1583d218ecae9c634c81497c21fee019791c2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9ded2dd60f65f26df5de63163fe14bad559a96e6e1918879295d09654cf73eda7267bb9df3721696e2ebee241f8e80bf4d5155f7e7ea332bf465738b78cb5c20", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/23d654692365c65084807fd0686957d2f251b9cbaa3a0f621ebdeb89b516ddfe3e9430f3730beb105148492fa66e9bd596cf073d7185bc81506266ae5c4c8f0e"} build_url:https://queue.taskcluster.net/v1/task/JhuDbI3YQeS1KwM6F3P5lg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011461 build_url: 'https://queue.taskcluster.net/v1/task/JhuDbI3YQeS1KwM6F3P5lg/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/da0cdfab7225c4cc890e8f4f1edb7b3e36bac2e31e58be645b05b63de8ae605b509ba2c976675ed45e29cdab7b670a5b285a334f3c3ad44d396eb3e910abba37", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/570e43f90e181b2e87b3bb93b56e19ff6cd6d82284abe6da6076b065a12f48e5a88ff9ff6d5efb26ae5ea55199e1583d218ecae9c634c81497c21fee019791c2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9ded2dd60f65f26df5de63163fe14bad559a96e6e1918879295d09654cf73eda7267bb9df3721696e2ebee241f8e80bf4d5155f7e7ea332bf465738b78cb5c20", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/23d654692365c65084807fd0686957d2f251b9cbaa3a0f621ebdeb89b516ddfe3e9430f3730beb105148492fa66e9bd596cf073d7185bc81506266ae5c4c8f0e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-02 00:35:27.873454) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-02 00:35:27.873905) ========= 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=c6927cf182745ad25b1d38b9000001fe-1501645127.809833-2090461881 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004422 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-02 00:35:27.956196) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-02 00:35:27.959535) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-02 00:35:27.959821) ========= ========= Total master_lag: 0.17 =========