builder: mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s slave: talos-linux64-ix-044 starttime: 1502835302.72 results: success (0) revision: d25db0546c92afa72d61685c9310104ef28235bd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-15 15:15:02.723786) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-15 15:15:02.724258) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-15 15:15:02.724628) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-15 15:15:02.740893) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-15 15:15:02.741174) ========= 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=88e9d754c8c9af9f7805d7f3000001bd-1502835077.167429-1301068326 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-15 15:15:03-- 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% 46.3M=0s 2017-08-15 15:15:03 (46.3 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.078446 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-15 15:15:02.847355) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-15 15:15:02.847662) ========= 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=88e9d754c8c9af9f7805d7f3000001bd-1502835077.167429-1301068326 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015450 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-15 15:15:02.906050) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 12 secs) (at 2017-08-15 15:15:02.906378) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d25db0546c92afa72d61685c9310104ef28235bd --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 d25db0546c92afa72d61685c9310104ef28235bd --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=88e9d754c8c9af9f7805d7f3000001bd-1502835077.167429-1301068326 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-15 15:15:03,447 truncating revision to first 12 chars 2017-08-15 15:15:03,447 Setting DEBUG logging. 2017-08-15 15:15:03,447 attempt 1/10 2017-08-15 15:15:03,447 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d25db0546c92?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-15 15:15:04,731 attempt 1/10 2017-08-15 15:15:05,571 current task status: no status available at this point. state: STARTED 2017-08-15 15:15:05,572 sleeping for 9.00s (attempt 1/10) 2017-08-15 15:15:14,581 attempt 2/10 2017-08-15 15:15:15,010 current task status: Task completed! Check 's3_urls' for upload locations. state: SUCCESS 2017-08-15 15:15:15,222 unpacking tar archive at: mozilla-central-d25db0546c92/testing/mozharness/ program finished with exit code 0 elapsedTime=12.113607 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 12 secs) (at 2017-08-15 15:15:15.040042) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-15 15:15:15.040356) ========= script_repo_revision: d25db0546c92afa72d61685c9310104ef28235bd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-15 15:15:15.040722) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-15 15:15:15.040994) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-15 15:15:15.052548) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-08-15 15:15:15.052851) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=88e9d754c8c9af9f7805d7f3000001bd-1502835077.167429-1301068326 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:15:15 INFO - MultiFileLogger online at 20170815 15:15:15 in /builds/slave/test 15:15:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 15:15:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:15:15 INFO - {'append_to_log': False, 15:15:15 INFO - 'base_work_dir': '/builds/slave/test', 15:15:15 INFO - 'blob_upload_branch': 'Firefox', 15:15:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:15:15 INFO - 'branch': 'Firefox', 15:15:15 INFO - 'buildbot_json_path': 'buildprops.json', 15:15:15 INFO - 'code_coverage': False, 15:15:15 INFO - 'config_files': ('talos/linux_config.py',), 15:15:15 INFO - 'default_actions': ('clobber', 15:15:15 INFO - 'read-buildbot-config', 15:15:15 INFO - 'download-and-extract', 15:15:15 INFO - 'populate-webroot', 15:15:15 INFO - 'create-virtualenv', 15:15:15 INFO - 'install', 15:15:15 INFO - 'setup-mitmproxy', 15:15:15 INFO - 'run-tests'), 15:15:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:15:15 INFO - 'disable_ccov_upload': False, 15:15:15 INFO - 'download_minidump_stackwalk': True, 15:15:15 INFO - 'download_symbols': 'ondemand', 15:15:15 INFO - 'enable_stylo': False, 15:15:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:15:15 INFO - 'tooltool.py': '/tools/tooltool.py', 15:15:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:15:15 INFO - '/tools/misc-python/virtualenv.py')}, 15:15:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:15:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:15:15 INFO - 'gecko_profile': False, 15:15:15 INFO - 'gecko_profile_interval': 0, 15:15:15 INFO - 'installer_path': 'installer.exe', 15:15:15 INFO - 'log_level': 'info', 15:15:15 INFO - 'log_name': 'talos', 15:15:15 INFO - 'log_to_console': True, 15:15:15 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:15:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:15:15 INFO - 'opt_config_files': (), 15:15:15 INFO - 'pip_index': False, 15:15:15 INFO - 'suite': 'dromaeojs-e10s', 15:15:15 INFO - 'system_bits': '32', 15:15:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:15:15 INFO - 'title': 'talos-linux64-ix-044', 15:15:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:15:15 INFO - 'use_talos_json': True, 15:15:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:15:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:15:15 INFO - 'work_dir': 'build'} 15:15:15 INFO - [mozharness: 2017-08-15 22:15:15.693882Z] Running clobber step. 15:15:15 INFO - Running pre-action listener: _resource_record_pre_action 15:15:15 INFO - Running main action method: clobber 15:15:15 INFO - rmtree: /builds/slave/test/build 15:15:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:15:17 INFO - Running post-action listener: _resource_record_post_action 15:15:17 INFO - [mozharness: 2017-08-15 22:15:17.238038Z] Finished clobber step (success) 15:15:17 INFO - [mozharness: 2017-08-15 22:15:17.238138Z] Running read-buildbot-config step. 15:15:17 INFO - Running pre-action listener: _resource_record_pre_action 15:15:17 INFO - Running main action method: read_buildbot_config 15:15:17 INFO - Using buildbot properties: 15:15:17 INFO - { 15:15:17 INFO - "project": "", 15:15:17 INFO - "product": "firefox", 15:15:17 INFO - "who": "asasaki@mozilla.com", 15:15:17 INFO - "installer_path": "public/build/target.tar.bz2", 15:15:17 INFO - "builddir": "mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s", 15:15:17 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 15:15:17 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo mozilla-central talos dromaeojs-e10s", 15:15:17 INFO - "stage_platform": "linux64-stylo", 15:15:17 INFO - "basedir": "/builds/slave/test", 15:15:17 INFO - "buildnumber": 34, 15:15:17 INFO - "platform": "ubuntu64_hw_stylo", 15:15:17 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 15:15:17 INFO - "slavebuilddir": "test", 15:15:17 INFO - "taskId": "RutGBnXOSQKV4HEpTbAoGA", 15:15:17 INFO - "branch": "mozilla-central", 15:15:17 INFO - "script_repo_revision": "production", 15:15:17 INFO - "revision": "d25db0546c92afa72d61685c9310104ef28235bd", 15:15:17 INFO - "slavename": "talos-linux64-ix-044", 15:15:17 INFO - "repo_path": "mozilla-central" 15:15:17 INFO - } 15:15:17 INFO - Finding installer, test and symbols from parent task. 15:15:17 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RutGBnXOSQKV4HEpTbAoGA'}, attempt #1 15:15:17 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RutGBnXOSQKV4HEpTbAoGA'}, attempt #1 15:15:18 INFO - Task dependencies: RMB3t9HoRkqz14OC2cjDLw 15:15:18 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RMB3t9HoRkqz14OC2cjDLw'}, attempt #1 15:15:18 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/RMB3t9HoRkqz14OC2cjDLw/artifacts/public/build/target.tar.bz2 15:15:18 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/RMB3t9HoRkqz14OC2cjDLw/artifacts/public/build/target.test_packages.json 15:15:18 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/RMB3t9HoRkqz14OC2cjDLw/artifacts/public/build/target.crashreporter-symbols.zip 15:15:18 INFO - Running post-action listener: _resource_record_post_action 15:15:18 INFO - [mozharness: 2017-08-15 22:15:18.635738Z] Finished read-buildbot-config step (success) 15:15:18 INFO - [mozharness: 2017-08-15 22:15:18.635958Z] Running download-and-extract step. 15:15:18 INFO - Running pre-action listener: _resource_record_pre_action 15:15:18 INFO - Running main action method: download_and_extract 15:15:18 INFO - mkdir: /builds/slave/test/build/tests 15:15:18 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')]} 15:15:18 INFO - trying https://queue.taskcluster.net/v1/task/RMB3t9HoRkqz14OC2cjDLw/artifacts/public/build/target.test_packages.json 15:15:18 INFO - Downloading https://queue.taskcluster.net/v1/task/RMB3t9HoRkqz14OC2cjDLw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 15:15:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RMB3t9HoRkqz14OC2cjDLw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 15:15:19 INFO - Downloaded 989 bytes. 15:15:19 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 15:15:19 INFO - Using the following test package requirements: 15:15:19 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 15:15:19 INFO - u'common': [u'target.common.tests.zip'], 15:15:19 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 15:15:19 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 15:15:19 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 15:15:19 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 15:15:19 INFO - u'mozbase': [u'target.common.tests.zip'], 15:15:19 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 15:15:19 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 15:15:19 INFO - u'web-platform': [u'target.common.tests.zip', 15:15:19 INFO - u'target.web-platform.tests.tar.gz'], 15:15:19 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 15:15:19 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 15:15:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RMB3t9HoRkqz14OC2cjDLw/artifacts/public/build/target.common.tests.zip 15:15:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RMB3t9HoRkqz14OC2cjDLw/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:15:19 INFO - Fetch https://queue.taskcluster.net/v1/task/RMB3t9HoRkqz14OC2cjDLw/artifacts/public/build/target.common.tests.zip into memory 15:15:21 INFO - Content-Length response header: 54002592 15:15:21 INFO - Bytes received: 54002592 15:15:32 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 15:15:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RMB3t9HoRkqz14OC2cjDLw/artifacts/public/build/target.common.tests.zip 15:15:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RMB3t9HoRkqz14OC2cjDLw/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:15:32 INFO - Fetch https://queue.taskcluster.net/v1/task/RMB3t9HoRkqz14OC2cjDLw/artifacts/public/build/target.common.tests.zip into memory 15:15:33 INFO - Content-Length response header: 54002592 15:15:33 INFO - Bytes received: 54002592 15:15:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RMB3t9HoRkqz14OC2cjDLw/artifacts/public/build/target.talos.tests.zip 15:15:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RMB3t9HoRkqz14OC2cjDLw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 15:15:45 INFO - Fetch https://queue.taskcluster.net/v1/task/RMB3t9HoRkqz14OC2cjDLw/artifacts/public/build/target.talos.tests.zip into memory 15:15:47 INFO - Content-Length response header: 14496050 15:15:47 INFO - Bytes received: 14496050 15:15:47 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')]} 15:15:47 INFO - trying https://queue.taskcluster.net/v1/task/RMB3t9HoRkqz14OC2cjDLw/artifacts/public/build/target.tar.bz2 15:15:47 INFO - Downloading https://queue.taskcluster.net/v1/task/RMB3t9HoRkqz14OC2cjDLw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 15:15:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RMB3t9HoRkqz14OC2cjDLw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 15:15:51 INFO - Downloaded 61907779 bytes. 15:15:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RMB3t9HoRkqz14OC2cjDLw/artifacts/public/build/target.tar.bz2 15:15:51 INFO - mkdir: /builds/slave/test/properties 15:15:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:15:51 INFO - Writing to file /builds/slave/test/properties/build_url 15:15:51 INFO - Contents: 15:15:51 INFO - build_url:https://queue.taskcluster.net/v1/task/RMB3t9HoRkqz14OC2cjDLw/artifacts/public/build/target.tar.bz2 15:15:51 INFO - Running post-action listener: _resource_record_post_action 15:15:51 INFO - Running post-action listener: set_extra_try_arguments 15:15:51 INFO - [mozharness: 2017-08-15 22:15:51.524989Z] Finished download-and-extract step (success) 15:15:51 INFO - [mozharness: 2017-08-15 22:15:51.525088Z] Running populate-webroot step. 15:15:51 INFO - Running pre-action listener: _resource_record_pre_action 15:15:51 INFO - Running main action method: populate_webroot 15:15:51 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:15:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:15:51 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:15:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:15:51 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:15:51 INFO - 'tests': ['damp', 'tps']}, 15:15:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:15:51 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 15:15:51 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 15:15:51 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:15:51 INFO - 'other-e10s': {'tests': ['a11yr', 15:15:51 INFO - 'ts_paint', 15:15:51 INFO - 'tpaint', 15:15:51 INFO - 'sessionrestore', 15:15:51 INFO - 'sessionrestore_many_windows', 15:15:51 INFO - 'sessionrestore_no_auto_restore', 15:15:51 INFO - 'tabpaint']}, 15:15:51 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 15:15:51 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 15:15:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:15:51 INFO - 'tsvgr_opacity', 15:15:51 INFO - 'tart', 15:15:51 INFO - 'tscrollx', 15:15:51 INFO - 'tsvg_static']}, 15:15:51 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:15:51 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:15:51 INFO - 'talos_options': ['--mitmproxy', 15:15:51 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:15:51 INFO - '--firstNonBlankPaint'], 15:15:51 INFO - 'tests': ['tp6_google', 15:15:51 INFO - 'tp6_youtube', 15:15:51 INFO - 'tp6_amazon', 15:15:51 INFO - 'tp6_facebook']}, 15:15:51 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:15:51 INFO - 'talos_options': ['--stylo', 15:15:51 INFO - '--mitmproxy', 15:15:51 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:15:51 INFO - '--firstNonBlankPaint'], 15:15:51 INFO - 'tests': ['tp6_google', 15:15:51 INFO - 'tp6_youtube', 15:15:51 INFO - 'tp6_amazon', 15:15:51 INFO - 'tp6_facebook']}, 15:15:51 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:15:51 INFO - 'talos_options': ['--stylo', 15:15:51 INFO - '--stylo-threads=1', 15:15:51 INFO - '--mitmproxy', 15:15:51 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:15:51 INFO - '--firstNonBlankPaint'], 15:15:51 INFO - 'tests': ['tp6_google', 15:15:51 INFO - 'tp6_youtube', 15:15:51 INFO - 'tp6_amazon', 15:15:51 INFO - 'tp6_facebook']}, 15:15:51 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:15:51 INFO - 'talos_options': ['--xperf_path', 15:15:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:15:51 INFO - 'tests': ['tp5n']}}} 15:15:51 INFO - Running post-action listener: _resource_record_post_action 15:15:51 INFO - [mozharness: 2017-08-15 22:15:51.533610Z] Finished populate-webroot step (success) 15:15:51 INFO - [mozharness: 2017-08-15 22:15:51.533700Z] Running create-virtualenv step. 15:15:51 INFO - Running pre-action listener: _resource_record_pre_action 15:15:51 INFO - Running main action method: create_virtualenv 15:15:51 INFO - Creating virtualenv /builds/slave/test/build/venv 15:15:51 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 15:15:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:15:51 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 15:15:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:15:51 INFO - Using real prefix '/usr' 15:15:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:15:52 INFO - Installing distribute.............................................................................................................................................................................................done. 15:15:54 INFO - Installing pip.................done. 15:15:54 INFO - Return code: 0 15:15:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:15:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:15:54 INFO - Reading from file tmpfile_stdout 15:15:54 INFO - Output received: 15:15:54 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) 15:15:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:15:54 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')]} 15:15:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:15:54 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')]} 15:15:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:15:54 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 0x21d41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23a0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2543700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25408a0>, '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 0x25417a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2541c30>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1502835077.167429-1301068326', '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 15:15:54 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 15:15:54 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 15:15:54 INFO - Using env: {'DISPLAY': ':0', 15:15:54 INFO - 'HOME': '/home/cltbld', 15:15:54 INFO - 'LANG': 'en_US.UTF-8', 15:15:54 INFO - 'LANGUAGE': 'en_US:en', 15:15:54 INFO - 'LOGNAME': 'cltbld', 15:15:54 INFO - 'MAIL': '/var/mail/cltbld', 15:15:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:15:54 INFO - 'MOZ_NO_REMOTE': '1', 15:15:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:15:54 INFO - 'NO_EM_RESTART': '1', 15:15:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:15:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:15:54 INFO - 'PWD': '/builds/slave/test', 15:15:54 INFO - 'SHELL': '/bin/bash', 15:15:54 INFO - 'SHLVL': '1', 15:15:54 INFO - 'TERM': 'linux', 15:15:54 INFO - 'TMOUT': '86400', 15:15:54 INFO - 'USER': 'cltbld', 15:15:54 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1502835077.167429-1301068326', 15:15:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:15:54 INFO - '_': '/tools/buildbot/bin/python'} 15:15:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:15:54 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 15:15:54 INFO - Cleaning up... 15:15:54 INFO - Return code: 0 15:15:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:15:54 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')]} 15:15:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:15:54 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')]} 15:15:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:15:54 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 0x21d41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23a0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2543700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25408a0>, '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 0x25417a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2541c30>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1502835077.167429-1301068326', '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 15:15:54 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 15:15:54 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 15:15:54 INFO - Using env: {'DISPLAY': ':0', 15:15:54 INFO - 'HOME': '/home/cltbld', 15:15:54 INFO - 'LANG': 'en_US.UTF-8', 15:15:54 INFO - 'LANGUAGE': 'en_US:en', 15:15:54 INFO - 'LOGNAME': 'cltbld', 15:15:54 INFO - 'MAIL': '/var/mail/cltbld', 15:15:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:15:54 INFO - 'MOZ_NO_REMOTE': '1', 15:15:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:15:54 INFO - 'NO_EM_RESTART': '1', 15:15:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:15:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:15:54 INFO - 'PWD': '/builds/slave/test', 15:15:54 INFO - 'SHELL': '/bin/bash', 15:15:54 INFO - 'SHLVL': '1', 15:15:54 INFO - 'TERM': 'linux', 15:15:54 INFO - 'TMOUT': '86400', 15:15:54 INFO - 'USER': 'cltbld', 15:15:54 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1502835077.167429-1301068326', 15:15:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:15:54 INFO - '_': '/tools/buildbot/bin/python'} 15:15:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:15:54 INFO - Downloading/unpacking psutil>=3.1.1 15:15:54 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 15:15:54 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 15:15:54 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 15:15:54 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 15:15:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:15:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:15:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:15:56 INFO - Installing collected packages: psutil 15:15:56 INFO - Running setup.py install for psutil 15:15:56 INFO - building 'psutil._psutil_linux' extension 15:15:56 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 15:15:56 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 15:15:56 INFO - building 'psutil._psutil_posix' extension 15:15:56 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 15:15:56 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 15:15:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:15:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:15:56 INFO - Successfully installed psutil 15:15:56 INFO - Cleaning up... 15:15:56 INFO - Return code: 0 15:15:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:15:56 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')]} 15:15:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:15:56 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')]} 15:15:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:15:56 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 0x21d41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23a0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2543700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25408a0>, '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 0x25417a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2541c30>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1502835077.167429-1301068326', '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 15:15:56 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 15:15:56 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 15:15:56 INFO - Using env: {'DISPLAY': ':0', 15:15:56 INFO - 'HOME': '/home/cltbld', 15:15:56 INFO - 'LANG': 'en_US.UTF-8', 15:15:56 INFO - 'LANGUAGE': 'en_US:en', 15:15:56 INFO - 'LOGNAME': 'cltbld', 15:15:56 INFO - 'MAIL': '/var/mail/cltbld', 15:15:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:15:56 INFO - 'MOZ_NO_REMOTE': '1', 15:15:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:15:56 INFO - 'NO_EM_RESTART': '1', 15:15:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:15:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:15:56 INFO - 'PWD': '/builds/slave/test', 15:15:56 INFO - 'SHELL': '/bin/bash', 15:15:56 INFO - 'SHLVL': '1', 15:15:56 INFO - 'TERM': 'linux', 15:15:56 INFO - 'TMOUT': '86400', 15:15:56 INFO - 'USER': 'cltbld', 15:15:56 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1502835077.167429-1301068326', 15:15:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:15:56 INFO - '_': '/tools/buildbot/bin/python'} 15:15:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:15:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:15:56 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 15:15:56 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 15:15:56 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 15:15:56 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 15:15:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:15:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:15:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:15:57 INFO - Installing collected packages: mozsystemmonitor 15:15:57 INFO - Running setup.py install for mozsystemmonitor 15:15:58 INFO - Successfully installed mozsystemmonitor 15:15:58 INFO - Cleaning up... 15:15:58 INFO - Return code: 0 15:15:58 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:15:58 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')]} 15:15:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:15:58 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')]} 15:15:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:15:58 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 0x21d41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23a0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2543700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25408a0>, '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 0x25417a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2541c30>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1502835077.167429-1301068326', '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 15:15:58 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 15:15:58 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 15:15:58 INFO - Using env: {'DISPLAY': ':0', 15:15:58 INFO - 'HOME': '/home/cltbld', 15:15:58 INFO - 'LANG': 'en_US.UTF-8', 15:15:58 INFO - 'LANGUAGE': 'en_US:en', 15:15:58 INFO - 'LOGNAME': 'cltbld', 15:15:58 INFO - 'MAIL': '/var/mail/cltbld', 15:15:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:15:58 INFO - 'MOZ_NO_REMOTE': '1', 15:15:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:15:58 INFO - 'NO_EM_RESTART': '1', 15:15:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:15:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:15:58 INFO - 'PWD': '/builds/slave/test', 15:15:58 INFO - 'SHELL': '/bin/bash', 15:15:58 INFO - 'SHLVL': '1', 15:15:58 INFO - 'TERM': 'linux', 15:15:58 INFO - 'TMOUT': '86400', 15:15:58 INFO - 'USER': 'cltbld', 15:15:58 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1502835077.167429-1301068326', 15:15:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:15:58 INFO - '_': '/tools/buildbot/bin/python'} 15:15:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:15:58 INFO - Downloading/unpacking jsonschema==2.5.1 15:15:58 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 15:15:58 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 15:15:58 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 15:15:58 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 15:15:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:15:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:15:59 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 15:15:59 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 15:15:59 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 15:15:59 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 15:15:59 INFO - Downloading functools32-3.2.3-2.tar.gz 15:15:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:15:59 INFO - warning: no files found matching '*.txt' 15:15:59 INFO - no previously-included directories found matching 'build' 15:15:59 INFO - no previously-included directories found matching 'dist' 15:15:59 INFO - no previously-included directories found matching '.git*' 15:15:59 INFO - Installing collected packages: jsonschema, functools32 15:15:59 INFO - Running setup.py install for functools32 15:15:59 INFO - warning: no files found matching '*.txt' 15:15:59 INFO - no previously-included directories found matching 'build' 15:15:59 INFO - no previously-included directories found matching 'dist' 15:15:59 INFO - no previously-included directories found matching '.git*' 15:15:59 INFO - Successfully installed jsonschema functools32 15:15:59 INFO - Cleaning up... 15:15:59 INFO - Return code: 0 15:15:59 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:15:59 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')]} 15:15:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:15:59 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')]} 15:15:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:15:59 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 0x21d41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23a0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2543700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25408a0>, '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 0x25417a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2541c30>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1502835077.167429-1301068326', '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 15:15:59 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 15:15:59 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 15:15:59 INFO - Using env: {'DISPLAY': ':0', 15:15:59 INFO - 'HOME': '/home/cltbld', 15:15:59 INFO - 'LANG': 'en_US.UTF-8', 15:15:59 INFO - 'LANGUAGE': 'en_US:en', 15:15:59 INFO - 'LOGNAME': 'cltbld', 15:15:59 INFO - 'MAIL': '/var/mail/cltbld', 15:15:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:15:59 INFO - 'MOZ_NO_REMOTE': '1', 15:15:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:15:59 INFO - 'NO_EM_RESTART': '1', 15:15:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:15:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:15:59 INFO - 'PWD': '/builds/slave/test', 15:15:59 INFO - 'SHELL': '/bin/bash', 15:15:59 INFO - 'SHLVL': '1', 15:15:59 INFO - 'TERM': 'linux', 15:15:59 INFO - 'TMOUT': '86400', 15:15:59 INFO - 'USER': 'cltbld', 15:15:59 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1502835077.167429-1301068326', 15:15:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:15:59 INFO - '_': '/tools/buildbot/bin/python'} 15:15:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:15:59 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:15:59 INFO - Cleaning up... 15:15:59 INFO - Return code: 0 15:15:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:15:59 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')]} 15:15:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:15:59 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')]} 15:15:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:15:59 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 0x21d41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23a0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2543700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25408a0>, '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 0x25417a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2541c30>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1502835077.167429-1301068326', '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 15:15:59 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 15:15:59 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 15:15:59 INFO - Using env: {'DISPLAY': ':0', 15:15:59 INFO - 'HOME': '/home/cltbld', 15:15:59 INFO - 'LANG': 'en_US.UTF-8', 15:15:59 INFO - 'LANGUAGE': 'en_US:en', 15:15:59 INFO - 'LOGNAME': 'cltbld', 15:15:59 INFO - 'MAIL': '/var/mail/cltbld', 15:15:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:15:59 INFO - 'MOZ_NO_REMOTE': '1', 15:15:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:15:59 INFO - 'NO_EM_RESTART': '1', 15:15:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:15:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:15:59 INFO - 'PWD': '/builds/slave/test', 15:15:59 INFO - 'SHELL': '/bin/bash', 15:15:59 INFO - 'SHLVL': '1', 15:15:59 INFO - 'TERM': 'linux', 15:15:59 INFO - 'TMOUT': '86400', 15:15:59 INFO - 'USER': 'cltbld', 15:15:59 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1502835077.167429-1301068326', 15:15:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:15:59 INFO - '_': '/tools/buildbot/bin/python'} 15:16:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:00 INFO - Downloading/unpacking blobuploader==1.2.4 15:16:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:16:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:16:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:16:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:16:01 INFO - Downloading blobuploader-1.2.4.tar.gz 15:16:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:16:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:16:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:16:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:16:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:16:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:16:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:16:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:16:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:16:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:16:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:16:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:16:01 INFO - Downloading docopt-0.6.1.tar.gz 15:16:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:16:01 INFO - Installing collected packages: blobuploader, requests, docopt 15:16:01 INFO - Running setup.py install for blobuploader 15:16:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:16:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:16:01 INFO - Running setup.py install for requests 15:16:02 INFO - Running setup.py install for docopt 15:16:02 INFO - Successfully installed blobuploader requests docopt 15:16:02 INFO - Cleaning up... 15:16:02 INFO - Return code: 0 15:16:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:16: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')]} 15:16:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16: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')]} 15:16:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:02 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 0x21d41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23a0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2543700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25408a0>, '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 0x25417a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2541c30>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1502835077.167429-1301068326', '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 15:16:02 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 15:16:02 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 15:16:02 INFO - Using env: {'DISPLAY': ':0', 15:16:02 INFO - 'HOME': '/home/cltbld', 15:16:02 INFO - 'LANG': 'en_US.UTF-8', 15:16:02 INFO - 'LANGUAGE': 'en_US:en', 15:16:02 INFO - 'LOGNAME': 'cltbld', 15:16:02 INFO - 'MAIL': '/var/mail/cltbld', 15:16:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:02 INFO - 'MOZ_NO_REMOTE': '1', 15:16:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:16:02 INFO - 'NO_EM_RESTART': '1', 15:16:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:16:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:02 INFO - 'PWD': '/builds/slave/test', 15:16:02 INFO - 'SHELL': '/bin/bash', 15:16:02 INFO - 'SHLVL': '1', 15:16:02 INFO - 'TERM': 'linux', 15:16:02 INFO - 'TMOUT': '86400', 15:16:02 INFO - 'USER': 'cltbld', 15:16:02 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1502835077.167429-1301068326', 15:16:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:02 INFO - '_': '/tools/buildbot/bin/python'} 15:16:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:16:02 INFO - Running setup.py (path:/tmp/pip-6fsv2x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:16:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:16:02 INFO - Running setup.py (path:/tmp/pip-102DVI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:16:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:16:02 INFO - Running setup.py (path:/tmp/pip-LYs4kJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:16:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:16:02 INFO - Running setup.py (path:/tmp/pip-Nllkdc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:16:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:16:02 INFO - Running setup.py (path:/tmp/pip-O82dVM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:16:03 INFO - Running setup.py (path:/tmp/pip-ENsoZn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:16:03 INFO - Running setup.py (path:/tmp/pip-g0PjXf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:16:03 INFO - Running setup.py (path:/tmp/pip-zydW5A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:16:03 INFO - Running setup.py (path:/tmp/pip-5LXMi5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:16:03 INFO - Running setup.py (path:/tmp/pip-r8n2eq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:16:03 INFO - Running setup.py (path:/tmp/pip-ecKTNq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:16:03 INFO - Running setup.py (path:/tmp/pip-1H4jR9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:16:03 INFO - Running setup.py (path:/tmp/pip-phlNkT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:16:03 INFO - Running setup.py (path:/tmp/pip-O9EY4g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:16:03 INFO - Running setup.py (path:/tmp/pip-J8KrVq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:16:03 INFO - Running setup.py (path:/tmp/pip-wy4r_b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:16:03 INFO - Running setup.py (path:/tmp/pip-iCievq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:16:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:16:03 INFO - Running setup.py install for manifestparser 15:16:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:16:03 INFO - Running setup.py install for mozcrash 15:16:04 INFO - Running setup.py install for mozdebug 15:16:04 INFO - Running setup.py install for mozdevice 15:16:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:16:04 INFO - Running setup.py install for mozfile 15:16:04 INFO - Running setup.py install for mozhttpd 15:16:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:16:04 INFO - Running setup.py install for mozinfo 15:16:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:16:04 INFO - Running setup.py install for mozInstall 15:16:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:16:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:16:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:16:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:16:04 INFO - Running setup.py install for mozleak 15:16:04 INFO - Running setup.py install for mozlog 15:16:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:16:04 INFO - Running setup.py install for moznetwork 15:16:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:16:04 INFO - Running setup.py install for mozprocess 15:16:05 INFO - Running setup.py install for mozprofile 15:16:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:16:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:16:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:16:05 INFO - Running setup.py install for mozrunner 15:16:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:16:05 INFO - Running setup.py install for mozscreenshot 15:16:05 INFO - Running setup.py install for moztest 15:16:05 INFO - Running setup.py install for mozversion 15:16:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:16:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:16:05 INFO - Cleaning up... 15:16:05 INFO - Return code: 0 15:16:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:16:05 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')]} 15:16:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16:05 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')]} 15:16:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:05 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 0x21d41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23a0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2543700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25408a0>, '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 0x25417a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2541c30>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1502835077.167429-1301068326', '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 15:16:05 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 15:16:05 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 15:16:05 INFO - Using env: {'DISPLAY': ':0', 15:16:05 INFO - 'HOME': '/home/cltbld', 15:16:05 INFO - 'LANG': 'en_US.UTF-8', 15:16:05 INFO - 'LANGUAGE': 'en_US:en', 15:16:05 INFO - 'LOGNAME': 'cltbld', 15:16:05 INFO - 'MAIL': '/var/mail/cltbld', 15:16:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:05 INFO - 'MOZ_NO_REMOTE': '1', 15:16:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:16:05 INFO - 'NO_EM_RESTART': '1', 15:16:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:16:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:05 INFO - 'PWD': '/builds/slave/test', 15:16:05 INFO - 'SHELL': '/bin/bash', 15:16:05 INFO - 'SHLVL': '1', 15:16:05 INFO - 'TERM': 'linux', 15:16:05 INFO - 'TMOUT': '86400', 15:16:05 INFO - 'USER': 'cltbld', 15:16:05 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1502835077.167429-1301068326', 15:16:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:05 INFO - '_': '/tools/buildbot/bin/python'} 15:16:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:16:05 INFO - Running setup.py (path:/tmp/pip-75MinA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:16:05 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)) 15:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:16:05 INFO - Running setup.py (path:/tmp/pip-6FLWLE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:16:06 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)) 15:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:16:06 INFO - Running setup.py (path:/tmp/pip-6dsVXT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:16:06 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)) 15:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:16:06 INFO - Running setup.py (path:/tmp/pip-Vk9L9n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:16:06 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)) 15:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:16:06 INFO - Running setup.py (path:/tmp/pip-e91dkK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:16:06 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)) 15:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:16:06 INFO - Running setup.py (path:/tmp/pip-EF5Lix-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:16:06 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)) 15:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:16:06 INFO - Running setup.py (path:/tmp/pip-ePQkOl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:16:06 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)) 15:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:16:06 INFO - Running setup.py (path:/tmp/pip-dHwF2K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:16:06 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)) 15:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:16:06 INFO - Running setup.py (path:/tmp/pip-GJVOMf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:16:06 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)) 15:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:16:06 INFO - Running setup.py (path:/tmp/pip-swR_d2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:16:06 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)) 15:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:16:06 INFO - Running setup.py (path:/tmp/pip-wMB8RE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:16:06 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)) 15:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:16:06 INFO - Running setup.py (path:/tmp/pip-U9Q5Pm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:16:06 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)) 15:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:16:06 INFO - Running setup.py (path:/tmp/pip-J0gecB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:16:06 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)) 15:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:16:06 INFO - Running setup.py (path:/tmp/pip-GEzqYe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:16:06 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)) 15:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:16:06 INFO - Running setup.py (path:/tmp/pip-Zc3otG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:16:06 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)) 15:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:16:06 INFO - Running setup.py (path:/tmp/pip-0mMLa8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:16:07 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)) 15:16:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:16:07 INFO - Running setup.py (path:/tmp/pip-pWvqwR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:16:07 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)) 15:16:07 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)) 15:16:07 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)) 15:16:07 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)) 15:16:07 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)) 15:16:07 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)) 15:16:07 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)) 15:16:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:16:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:16:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:16:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:16:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:16:08 INFO - Downloading blessings-1.6.tar.gz 15:16:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:16:08 INFO - Installing collected packages: blessings 15:16:08 INFO - Running setup.py install for blessings 15:16:08 INFO - Successfully installed blessings 15:16:08 INFO - Cleaning up... 15:16:08 INFO - Return code: 0 15:16:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:16:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:16:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:16:08 INFO - Reading from file tmpfile_stdout 15:16:08 INFO - Current package versions: 15:16:08 INFO - argparse == 1.2.1 15:16:08 INFO - blessings == 1.6 15:16:08 INFO - blobuploader == 1.2.4 15:16:08 INFO - docopt == 0.6.1 15:16:08 INFO - functools32 == 3.2.3-2 15:16:08 INFO - jsonschema == 2.5.1 15:16:08 INFO - manifestparser == 1.1 15:16:08 INFO - mozInstall == 1.13 15:16:08 INFO - mozcrash == 1.0 15:16:08 INFO - mozdebug == 0.1 15:16:08 INFO - mozdevice == 0.51 15:16:08 INFO - mozfile == 1.2 15:16:08 INFO - mozhttpd == 0.7 15:16:08 INFO - mozinfo == 0.10 15:16:08 INFO - mozleak == 0.1 15:16:08 INFO - mozlog == 3.5 15:16:08 INFO - moznetwork == 0.27 15:16:08 INFO - mozprocess == 0.25 15:16:08 INFO - mozprofile == 0.28 15:16:08 INFO - mozrunner == 6.13 15:16:08 INFO - mozscreenshot == 0.1 15:16:08 INFO - mozsystemmonitor == 0.3 15:16:08 INFO - moztest == 0.8 15:16:08 INFO - mozversion == 1.4 15:16:08 INFO - psutil == 3.1.1 15:16:08 INFO - requests == 1.2.3 15:16:08 INFO - wsgiref == 0.1.2 15:16:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:16: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')]} 15:16:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16: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')]} 15:16:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:08 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 0x21d41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23a0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2543700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25408a0>, '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 0x25417a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2541c30>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1502835077.167429-1301068326', '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 15:16:08 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 15:16:08 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 15:16:08 INFO - Using env: {'DISPLAY': ':0', 15:16:08 INFO - 'HOME': '/home/cltbld', 15:16:08 INFO - 'LANG': 'en_US.UTF-8', 15:16:08 INFO - 'LANGUAGE': 'en_US:en', 15:16:08 INFO - 'LOGNAME': 'cltbld', 15:16:08 INFO - 'MAIL': '/var/mail/cltbld', 15:16:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:08 INFO - 'MOZ_NO_REMOTE': '1', 15:16:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:16:08 INFO - 'NO_EM_RESTART': '1', 15:16:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:16:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:08 INFO - 'PWD': '/builds/slave/test', 15:16:08 INFO - 'SHELL': '/bin/bash', 15:16:08 INFO - 'SHLVL': '1', 15:16:08 INFO - 'TERM': 'linux', 15:16:08 INFO - 'TMOUT': '86400', 15:16:08 INFO - 'USER': 'cltbld', 15:16:08 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1502835077.167429-1301068326', 15:16:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:08 INFO - '_': '/tools/buildbot/bin/python'} 15:16:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:08 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)) 15:16:08 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)) 15:16:08 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)) 15:16:08 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)) 15:16:08 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)) 15:16:08 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)) 15:16:08 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)) 15:16:08 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)) 15:16:08 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)) 15:16:08 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 15:16: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 15:16: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 15:16: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 15:16: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 15:16:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 15:16:10 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)) 15:16:10 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)) 15:16:10 INFO - Installing collected packages: simplejson 15:16:10 INFO - Running setup.py install for simplejson 15:16:10 INFO - building 'simplejson._speedups' extension 15:16:10 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 15:16:10 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 15:16:11 INFO - Successfully installed simplejson 15:16:11 INFO - Cleaning up... 15:16:11 INFO - Return code: 0 15:16:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:16: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')]} 15:16:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16: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')]} 15:16:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21d41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23a0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2543700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25408a0>, '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 0x25417a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2541c30>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1502835077.167429-1301068326', '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 15:16: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', 'jsonschema'] in /builds/slave/test/build 15:16: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 jsonschema 15:16:11 INFO - Using env: {'DISPLAY': ':0', 15:16:11 INFO - 'HOME': '/home/cltbld', 15:16:11 INFO - 'LANG': 'en_US.UTF-8', 15:16:11 INFO - 'LANGUAGE': 'en_US:en', 15:16:11 INFO - 'LOGNAME': 'cltbld', 15:16:11 INFO - 'MAIL': '/var/mail/cltbld', 15:16:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:11 INFO - 'MOZ_NO_REMOTE': '1', 15:16:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:16:11 INFO - 'NO_EM_RESTART': '1', 15:16:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:16:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:11 INFO - 'PWD': '/builds/slave/test', 15:16:11 INFO - 'SHELL': '/bin/bash', 15:16:11 INFO - 'SHLVL': '1', 15:16:11 INFO - 'TERM': 'linux', 15:16:11 INFO - 'TMOUT': '86400', 15:16:11 INFO - 'USER': 'cltbld', 15:16:11 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1502835077.167429-1301068326', 15:16:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:11 INFO - '_': '/tools/buildbot/bin/python'} 15:16:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:11 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:16:11 INFO - Cleaning up... 15:16:11 INFO - Return code: 0 15:16:11 INFO - Running post-action listener: _resource_record_post_action 15:16:11 INFO - Running post-action listener: _start_resource_monitoring 15:16:11 INFO - Starting resource monitoring. 15:16:11 INFO - [mozharness: 2017-08-15 22:16:11.362770Z] Finished create-virtualenv step (success) 15:16:11 INFO - [mozharness: 2017-08-15 22:16:11.363289Z] Running install step. 15:16:11 INFO - Running pre-action listener: _resource_record_pre_action 15:16:11 INFO - Running main action method: install 15:16:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:16:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:16:11 INFO - Reading from file tmpfile_stdout 15:16:11 INFO - Detecting whether we're running mozinstall >=1.0... 15:16:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:16:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:16:11 INFO - Reading from file tmpfile_stdout 15:16:11 INFO - Output received: 15:16:11 INFO - Usage: mozinstall [options] installer 15:16:11 INFO - Options: 15:16:11 INFO - -h, --help show this help message and exit 15:16:11 INFO - -d DEST, --destination=DEST 15:16:11 INFO - Directory to install application into. [default: 15:16:11 INFO - "/builds/slave/test"] 15:16:11 INFO - --app=APP Application being installed. [default: firefox] 15:16:11 INFO - mkdir: /builds/slave/test/build/application 15:16:11 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'] 15:16:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 15:16:29 INFO - Reading from file tmpfile_stdout 15:16:29 INFO - Output received: 15:16:29 INFO - /builds/slave/test/build/application/firefox/firefox 15:16:29 INFO - Running post-action listener: _resource_record_post_action 15:16:29 INFO - [mozharness: 2017-08-15 22:16:29.686665Z] Finished install step (success) 15:16:29 INFO - [mozharness: 2017-08-15 22:16:29.686950Z] Running setup-mitmproxy step. 15:16:29 INFO - Running pre-action listener: _resource_record_pre_action 15:16:29 INFO - Running main action method: setup_mitmproxy 15:16:29 INFO - Skipping: mitmproxy is not required 15:16:29 INFO - Running post-action listener: _resource_record_post_action 15:16:29 INFO - [mozharness: 2017-08-15 22:16:29.688111Z] Finished setup-mitmproxy step (success) 15:16:29 INFO - [mozharness: 2017-08-15 22:16:29.688333Z] Running run-tests step. 15:16:29 INFO - Running pre-action listener: _resource_record_pre_action 15:16:29 INFO - Running pre-action listener: _set_gcov_prefix 15:16:29 INFO - Running main action method: run_tests 15:16:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:16:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:16:29 INFO - Python 2.7.3 15:16:29 INFO - Return code: 0 15:16:29 INFO - grabbing minidump binary from tooltool 15:16:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:16:29 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 0x25408a0>, '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 0x25417a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2541c30>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:16:29 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 15:16:29 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 15:16:29 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 15:16:29 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:16:29 INFO - Return code: 0 15:16:29 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:16:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:16:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:16:29 INFO - ENV: RUST_BACKTRACE is now full 15:16:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:16:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:16:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RMB3t9HoRkqz14OC2cjDLw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-044', '--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 15:16:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RMB3t9HoRkqz14OC2cjDLw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-044 --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 15:16:29 INFO - Using env: {'DISPLAY': ':0', 15:16:29 INFO - 'HOME': '/home/cltbld', 15:16:29 INFO - 'LANG': 'en_US.UTF-8', 15:16:29 INFO - 'LANGUAGE': 'en_US:en', 15:16:29 INFO - 'LOGNAME': 'cltbld', 15:16:29 INFO - 'MAIL': '/var/mail/cltbld', 15:16:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:16:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:16:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:29 INFO - 'MOZ_NO_REMOTE': '1', 15:16:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:16:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:16:29 INFO - 'NO_EM_RESTART': '1', 15:16:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:16:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:29 INFO - 'PWD': '/builds/slave/test', 15:16:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:16:29 INFO - 'RUST_BACKTRACE': 'full', 15:16:29 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:16:29 INFO - 'SHELL': '/bin/bash', 15:16:29 INFO - 'SHLVL': '1', 15:16:29 INFO - 'STYLO_FORCE_ENABLED': '1', 15:16:29 INFO - 'TERM': 'linux', 15:16:29 INFO - 'TMOUT': '86400', 15:16:29 INFO - 'USER': 'cltbld', 15:16:29 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1502835077.167429-1301068326', 15:16:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:29 INFO - '_': '/tools/buildbot/bin/python'} 15:16:29 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RMB3t9HoRkqz14OC2cjDLw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-044', '--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 15:16:30 INFO - mozversion application_buildid: 20170815212701 15:16:30 INFO - mozversion application_changeset: d25db0546c92afa72d61685c9310104ef28235bd 15:16:30 INFO - mozversion application_display_name: Nightly 15:16:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:16:30 INFO - mozversion application_name: Firefox 15:16:30 INFO - mozversion application_remotingname: firefox 15:16:30 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:16:30 INFO - mozversion application_vendor: Mozilla 15:16:30 INFO - mozversion application_version: 57.0a1 15:16:30 INFO - mozversion platform_buildid: 20170815212701 15:16:30 INFO - mozversion platform_changeset: d25db0546c92afa72d61685c9310104ef28235bd 15:16:30 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:16:30 INFO - mozversion platform_version: 57.0a1 15:16:30 INFO - using testdate: 1502835390 15:16:30 INFO - actual date: 1502835390 15:16:30 INFO - starting webserver on 'localhost:56348' 15:16:30 INFO - SUITE-START | Running 2 tests 15:16:30 INFO - TEST-START | dromaeo_css 15:16:30 INFO - Initialising browser for dromaeo_css test... 15:16:30 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:56348/getInfo.html -profile /tmp/tmphRFCkI/profile 15:16:30 INFO - TEST-INFO | started process 12191 (/builds/slave/test/build/application/firefox/firefox http://localhost:56348/getInfo.html) 15:16:35 INFO - TEST-INFO | 12191: exit 0 15:16:36 INFO - Browser initialized. 15:16:36 INFO - Running cycle 1/1 for dromaeo_css test... 15:16:36 INFO - TEST-INFO | started process 12427 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphRFCkI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 15:16:37 INFO - PID 12427 | 15:16:37 INFO - PID 12427 | (/builds/slave/test/build/application/firefox/firefox:12481): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:16:37 INFO - PID 12427 | 15:16:47 INFO - PID 12427 | RSS: Main: 167436288 15:16:47 INFO - PID 12427 | 15:19:45 INFO - PID 12427 | Cycle 1(1): loaded http://localhost:56348/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56348/tests/dromaeo/cssquery-ext.html) 15:19:47 INFO - PID 12427 | RSS: Main: 175087616 15:19:47 INFO - PID 12427 | 15:22:49 INFO - PID 12427 | Cycle 1(1): loaded http://localhost:56348/tests/dromaeo/cssquery-ext.html (next: http://localhost:56348/tests/dromaeo/cssquery-jquery.html) 15:22:49 INFO - PID 12427 | RSS: Main: 172150784 15:22:49 INFO - PID 12427 | 15:25:51 INFO - PID 12427 | Cycle 1(1): loaded http://localhost:56348/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56348/tests/dromaeo/cssquery-mootools.html) 15:25:52 INFO - PID 12427 | RSS: Main: 173842432 15:25:52 INFO - PID 12427 | 15:28:53 INFO - PID 12427 | Cycle 1(1): loaded http://localhost:56348/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56348/tests/dromaeo/cssquery-prototype.html) 15:28:54 INFO - PID 12427 | RSS: Main: 170569728 15:28:54 INFO - PID 12427 | 15:31:58 INFO - PID 12427 | Cycle 1(1): loaded http://localhost:56348/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56348/tests/dromaeo/cssquery-yui.html) 15:31:58 INFO - PID 12427 | RSS: Main: 175992832 15:31:58 INFO - PID 12427 | 15:35:00 INFO - PID 12427 | Cycle 1(1): loaded http://localhost:56348/tests/dromaeo/cssquery-yui.html (next: http://localhost:56348/tests/dromaeo/cssquery-dojo.html) 15:35:01 INFO - PID 12427 | RSS: Main: 174600192 15:35:01 INFO - PID 12427 | 15:35:01 INFO - PID 12427 | __start_tp_report 15:35:01 INFO - PID 12427 | _x_x_mozilla_page_load 15:35:01 INFO - PID 12427 | _x_x_mozilla_page_load_details 15:35:01 INFO - PID 12427 | |i|pagename|runs| 15:35:01 INFO - PID 12427 | |0;dojo.html;3110.7511399088075;3151.7005884441;3151.9527207091896;3163.58873346465;3205.8236796976157;6078.422549857767;6244.000959846426;6246.968765156179;6251.187345645066;6290.119383286344;6022.548308876841;6139.293981192164;6140.416660417264;6145.047517634774;6162.568620196588;5566;5582.051051321275;5616.157576363557;5620.662760234379;5627.212190293362;5571.164325351189;5652.576056795736;5692.345380281258;5746.885062298751;5768.250127483421;5618.747156377973;5703.116017017348;5703.201551782754;5704.572157088235;5729.026065568843;5466.289382380265;5536.529395001429;5552.194931734876;5648.03983322834;5715.828525144252;5532.972335138298;5556.86107847303;5560.304961879765;5584.944150558483;5603.7478313475585;5871.823845284605;6011.218541589566;6063.18147050147;6110.022396416552;6121.693915304217;6540.9345906541275;6586.604803711744;6604.768833090819;6625.834354141137;6662.600243985376;6716.361945615159;6735.360140786666;6758.222804377461;6779.898301525481;6785.660716964181;6156.722947467375;6169.01295792671;6235.719392627298;6239.157713708637;6253.749850005995;6678.632675202911;6811.148606424197;6902.6203558803745;6911.827204319831;6915.308469153045;6468.708908099147;6473.029045643097;6566.934330656728;6572.737090516326;6573;6548.9017664735065;6610.933890661128;6611.140551728341;6624.072629831828;6625.105610742585;6485.059666348353;6500.512461565401;6515.02274658805;6572.178477690289;6600.900986485207;6471.029345598198;6535.281119076898;6567.802965911031;6624.801255962232;6629.7679581214425;5252.474752524793;5255.474452554714;5256.39551451588;5261.842144735664;5263.789448422097;4517.299818528133;4535.818567257339;4537.795799189044;4541.091781643684;4542.0915816836105;5602.747876345574;5611.9438805612235;5624.3813180550105;5627.943720562742;5702.116171993348;6530.05314229434;6589.17635295588;6595.175603049619;6606.735730570819;6646.235682896432;5626.662400255997;5648.209250704905;5702.885942281131;5721.456461636138;5734.770609175586;5741.712914354265;5752.050911599539;5798.333191682926;5817.418258174149;5820.35976042635;6200.255969283715;6288.496920246369;6386.36136386367;6441.227052753606;6490.545661803723;6724.4284235840505;6726.529142959946;6758.662066896733;6763.188417389944;6779.220389655055;6393.840153996187;6419.582727122722;6487.610743355225;6506.3818937200895;6544.705488252945;6234.625922444487;6492.156019717407;6550.181227346582;6581.736730530725;6618.933810661832;6561.540692151503;6564.9671751640935;6642.036904648702;6645.800625981228;6651.866962660622;6412.839679008062;6418.454431373386;6453.419192272718;6459.515536334888;6495.090687303876;6384.776532821329;6395.968020160056;6492;6495.285518593046;6507;6189.381061893955;6334.651594162366;6337.397947195009;6431.324710905287;6436.227652681709;6355.682215889095;6381.234251889803;6417.2620148681635;6419.358064193543;6422.550421470639;6212.689365531615;6235.127082208585;6246.156768836149;6310.873782524415;6320.715567799368 15:35:01 INFO - PID 12427 | |1;ext.html;816.6080281464897;841.7727213652314;870.4777133719767;883.6156272021672;1154.6189757380066;31533.526997095032;32274;42655.36016959744;43356;43408.56591434085;42808.571914280845;42925;42949.35575966363;43487.565124348825;43551;7504.399648028145;7653.272939070779;7655.732049378273;7662.885056724153;7713.1515533291295;7561.62191890406;7627.618619069052;7662.808429789244;7666.578338191397;7753.147153813076;7630.50401723889;7671.156172820985;7764.611769411535;7776.65005074773;7795.14253432122;7649.120351159604;7673.117591476968;7678.001859758224;7678.270564296383;7680.423968202407;7588.354989825872;7627.961860190719;7653.502522336031;7669.616519174075;7673.9616301918695;38312.233755324734;38422.423663645066;38580.80709596434;38672.22655546873;38875.416868746994;3497.720182385403;3530.735194860396;3533.116720819795;3541.7166626669805;3544.113971507123;31881;31932.680673193205;32024.519632205534;32058;32086;5000.89998200034;5019.498050194988;5028.723420211887;5038.5969122470115;5066.9746651266505;179015;180807;181107;181270;181376;48805.26792098121;48818.75590622024;48918;48920;49117.508824911296;51016;51313;51388;51450.74274628603;51537;14543.636409089857;14805.555833325017;16414.917925410296;16881.493555193363;16894.239759210865;12460.688482787822;12542.122051456492;12569.371531423392;12570.68573285657;12605.243685378724;14819.629509262139;14843.183624900517;14952.327145278488;14961.925190373979;15007;5910;6089.117078023662;6089.512838972872;6102.908456373158;6114.266288045463;1001.8146642871092;1019.8623185869812;1021.3055122516612;1022.3508072374096;1029.2537910015178;23152.88423557905;23255.3023409298;23332.416689582897;23418.297451076494;23527.7647223529;1106.778644271149;1108.711734948919;1119.3731510354228;1132.2074547816562;1132.4903793293051;12334.629961101182;12338;12341.814872776915;12413.06901982355;12509;230013;231720;231763;231879;233438;53740.73129634405;54288.185677214875;54339;54423.72788136034;54473;1458.6207586027497;1468.3392473387019;1479.275155173957;1497.52827859224;1523.9619009524852;23116.3065108047;23202.767972320737;23298.883505582362;23307.18424855122;23437.296881093593;37792.244155117296;37826;37892.62107379001;37912.62087379091;37939.430908536946;188582;188673;188849;188976;189122;35306;36545.634543654225;38910.221795564496;39081.804590978;39374.80312598419;46080.308795367426;48274.517254827006;48289.27566086441;48471.75764121157;48982;34865;35002.29995400027;35025.64974350326;35395.11512212215;35399;34271.31457370789;34275.82862085674;34281.65718342884;35147.29705405826;35238.47142292817;37576;37594.436083458226;37608.811955940044;37890.62109378871;38004.61995380011;33436;33805.3238935215;33884.152896177795;34046.319073618884;34288;24935.501289973738;25069.373265668502;25112.995480180587;25263.863126159733;25364.873175634002 15:35:01 INFO - PID 12427 | |2;jquery.html;574.7988204128555;577.933537643171;580.875111850952;581.8429024163479;593.9406059394062;22453.55092898141;22767.316980490592;23685.40786480337;23721.406964825866;23731.169409070684;24968.250952471408;26672;26691.466170676576;26696;26711.732882671167;6627.072209890631;6774.5257831951785;6784;6784.9321506785045;6786.083878676365;6041.214642096521;6582.572132811376;6587.275399706028;6617.503687223453;6678.465722742193;6527.738890444401;6529.771457998971;6536.4770818334655;6536.836579085513;6537.869242615145;6501.154849869534;6528.771492997722;6530;6546.738130474751;6547.803565892983;5994.520438364921;6057.121717351003;6058.3032951210735;6081.969590152065;6099.847503812396;28681.85659071712;29437.85281073602;30030;30928.8453557733;31044.53433198504;1919.1076149590508;1962.9214831406728;1962.931297404584;1963.352093809054;1965.6167047425758;19816.80183198164;20304.289349872684;20418.387448376572;20434.795652043438;20476;2300.608896487601;2345.9296221113395;2347.5891718949115;2364.9527009459716;2472.4560596668703;138817;140124;142242;142632;143044.99999999895;14039.82382730658;14733;15143.167125807966;15192.240387980557;15340.002899811476;23725.762742372357;23906.880465597562;23912.7608723914;23946.161884333964;23960.760392395852;9838.311318207656;9890.802183956424;9910.900890991143;9965.701028969142;10009.249306302056;8997.37018408705;9027.322950778718;9031;9081.636734530677;9173.862392064124;7934.603269836485;9226.30802689801;9340.4862732549;9470.668526601536;9605.47169905648;2735.2341344423594;2759.599858020576;2767.1283545683045;2805.8456784877026;2828.6747024092083;961.4519723757531;974.6004138303261;975.1272611013245;998.6654470752234;1000.0849222961054;19553.902230489042;19558;19625.411237662895;19847.801521984886;23585.52828943446;992.6823416506793;1061.9097376722912;1064.3932958213898;1068.3696618994675;1081.2701426537055;11170.609028683957;11218.831717524243;11220.887791122148;11291.153163512769;11292.604758833402;450911;464402;465923;475519;521471;117072.41463792627;119841;119885;119983;123323;1251.443107817031;1272.204871955028;1287.7424515097018;1287.9871201288054;1294.4822071171418;17421.47735567935;17523;17699.469015929542;17910.104494774947;18076.72884906754;52816;52969.470305296454;53310.46689533055;53848.73075634597;54926;65805.67097164484;73983;74296.62851685923;74398.25601743913;75263.8710419355;14428.134311941318;14775.187364695044;14813.259337033322;14838.18389988561;14857.554273371816;22823.885880571153;23016.84915754172;23058.654120187875;23215.419614509774;23232.651510227697;16889.155542223085;16995.32018719287;17018.149092545074;17607.735883961494;17836.732449013536;16696;16795.41216057432;17185.14074296255;17263.13684315754;17361.913190433967;16875.74686379681;17349.82650173482;18465.90767046156;18913.621727565096;18985.43043708691;14457.92771036138;14688.192149431883;14732;14761.852381476478;14766.187859667822;16864.747028794332;17057.317707292066;17099.487015389557;17469.213885374935;18545.258189672262 15:35:01 INFO - PID 12427 | |3;mootools.html;1332;1367.9658008549784;1403.9157650540963;1415.179196066282;1416.5962700630323;14091.15453072815;14319;14758.557243282705;14832.110073395605;14846.628834279149;14888.478903238389;15193.848061519382;15213.619659508518;15240.923795381008;15245.542633721006;2648.3643925457945;2708.945821083587;2709.4716530276605;2712.3625947902324;2713.145359211852;2613.2813476294077;2809.9157025289273;2810.8454035028067;2811.11449893284;2813.395120049189;2677.4377380750066;2686.717894621066;2710.254679963016;2713.579395193748;2716.5925111233373;2656.3890305229806;2678.129607877438;2692.7711144552736;2727.481778462106;2727.890884364623;2630.88161032754;2695.555233386489;2711.769499592537;2713;2713.1046754570943;14149.646258843612;14259.857401426061;14417.78373324401;14419.495317663832;14428.855711442857;7572.697092116308;7882.921170788276;7917.643706033243;7921.762347129596;7931.206879312081;4945.9258111128365;5135;5276.630635855492;5310.734463276859;5311.28297679816;1809.8190180981928;1842.456475339778;1887.9899253899234;1909.484439201408;1926.8940208288539;24981.750182498305;25201.62197567038;25227.747722522723;25249.863756130835;25314.620280695803;6590.934090659128;6968.512204145763;7174.78475645721;7178.425725941912;7233;16318.836811631969;16398.26207820651;16442.095684737214;16478.09370484635;16493.917530412273;7929.1277959424415;8212.34301255898;8216.383771217183;8234.670613175405;8248.587570621445;6153.507719382439;7119.786406407816;7217.566945983152;7322.1945585985495;7327.45044121693;4740.786664600101;4933.481984391659;5047.343845300161;5205.193195054772;5274.841754747364;1510.5543864559982;1515.5756388211319;1529.388244702128;1535.293764868151;1540.8998415103206;905.2124651553191;915.7390143808982;917.7613820406609;925.796324808541;928.3037721708719;8056.83886322282;8397;8511.319094472427;8526.190011948855;8541.95729021351;999.6201443451442;1007.0483393193508;1020.3673722292078;1020.8774947006258;1022.9130523905402;5318.361796584435;5447.155690867842;5461.126219804892;5713.542916566665;5747.425257474303;19694.21223151058;20283.8985805073;20344.287949921978;20436.182552698025;20437.693434598492;19225.230990760494;19977.500562486053;20035.699464508045;20075.498112547302;20384.28654997068;10309.793804123876;10407.63573274947;10486;10491.475426228812;10534.841977370346;8211.630476628685;8440.113788052411;8746.825063498822;8966.955165224394;9115.316351273814;17645.735313970043;17731.556711082325;17912.552186194927;18239.726404104214;18520.16659250364;17829.46511604654;17841.910790445963;17952.820471795116;18017.459476215736;18123.45629631113;10744.677659670222;11380;11762.70593235176;11789.292642441482;11794;12207.816882746589;13249;13589.116707413987;13599.52401665937;13676.658083547592;11125.388103654377;11261.324320540794;11287.661370158909;11405;11511.30932144074;10768.892311076788;10790.082842958123;10940.45297735126;10999.835002474809;11150.498227579616;9302.02328755471;9477.715668529956;9539.379940303857;9542.141207291377;9559;7405.296496832792;7416.147143078615;7420.406367490696;7471.925280747123;7475.327220550176;7208.495405321577;7213;7244.166920803964;7258.745943891939;7327.890081648886 15:35:01 INFO - PID 12427 | |4;prototype.html;485.32425847867285;493.33417332603204;514.061837147206;520.240448944541;522.2114606943514;6166.964901582478;6171.691415429222;6193;6197.985654662236;6246.250449946007;5909.379515150912;6094.9085763713465;6138.938610613893;6176.567640265183;6189.81430557084;4770.403699537558;4885.682430641997;4923.655344125913;4944.085344211315;4976.7013979161275;4922.926156107661;4954.7522623868845;4968.652194346397;4979.850604481871;5004.299398084253;4728.314394412808;4879.730580511066;4880.389951256093;4885.853424397274;4890.266460031006;4878.975225281504;4884.560389564938;4887.995921672115;4895.31465594817;4900.117978763821;4353.695241333108;4363.585459381354;4373.343998400233;4388.5830846069575;4410.140022695576;6050.122732203849;6072.392760723937;6075.574709770318;6088.634681919055;6093.542984276197;5618.63478873876;5626.9437305626825;5640.5463623323985;5669.829905102853;5675.5459563234845;6428.742850285983;6488.28628850826;6499.740010399579;6500.674966251716;6537.248216455121;4722.197226471508;4750.311204875274;4752.9762351188365;4768.332433459318;4776.99366266169;6739.44992651688;6804.829879253059;6836.077129587541;6888.896666550006;6899.896501552481;4830.372051633335;4831.178699621073;4872.171730805771;4888.706677599355;4904.60763138948;4929.852104436873;4937.136001199848;4941.184704523714;4967.726775027336;4972.7513624318635;5572.247746554244;5582.553395728413;5592.552595792326;5623.746931388097;5638.23883775685;6445.45213656835;6481.222253329631;6486.643234622141;6552.6068435894;6572;1619.8218195998431;1693.889897156681;1722.7157519009475;1734.514335985926;1738.19174084049;1132.2413982631656;1158.478684591937;1163.3717792392183;1171.4377098992532;1194.4505527457468;5592.692401917933;5635.126555383922;5687.146927960756;5706;5766.221560089418;6453.741850325934;6528.967355163194;6628.304028077079;6662.600243985376;6684.398404143651;5665.461781130786;5710.632005914427;5719.799807006818;5761.683107429048;5786.842631473722;3499.5975462822103;3501.8949431517094;3510.157562185057;3560.181158333598;3583.283343331344;6415.807525774234;6428.5178611604315;6438.967805160944;6486.54594178403;6510.777165303205;6844.657767111625;6861.451083913275;6871.209810871814;6871.862562748817;6907.481938854606;6389.297177310492;6409.839754006187;6452.161219041494;6467.967660161763;6484.351564843478;6579.078928950046;6595.571287866273;6708.563943343667;6711.389281557759;6737.797866064026;6579.736810527525;6627.569208001464;6639.136912201383;6693;6693.598384096969;6464.545477267765;6468.256150542559;6539.901901471387;6549.214094308713;6553.803385898431;4809.567138957511;4824.131656301899;4830.927536086891;4832.734199619054;4877.292792545132;6628.520026985871;6635.867282654416;6647.434968027772;6681.398674119353;6728.798136055926;6413.198350206224;6422.807315780534;6470.22357317125;6504.837379065561;6525.8694826104165;6246.843828904313;6396.520260980538;6422.935770642421;6484.1246431731115;6573.309802470863;6073.696315184311;6252.968735156295;6302.432781049728;6363.3000369958345;6380.266269379081;6501.577397469149;6529.085927969993;6534.313897040743;6536.542442029012;6541.836454088686;6361.522885783492;6364.77723279683;6373.788980093767;6375.808725738235;6385.744570217139 15:35:01 INFO - PID 12427 | |5;yui.html;906.9954650226748;909.1999040844058;932.454514109246;936.3726303376741;942.0438255171;18708.90645546772;19601.60796784067;19657.410277691655;19671.704924426143;21333.25333613324;21073.367798966057;21777.3466795996;21893.562128757454;21922.561548769016;21938.122475100976;1036.1658864613983;1046.016744260393;1048.8374301983206;1048.973775655611;1049.5539395756812;1022.6625213679505;1047.7590154264524;1055.5144633468615;1055.9683209503728;1059.9364038157696;1095.6822521468764;1100.1199040767394;1100.1748688483638;1100.818364969779;1100.9229353945186;1091.5961094395125;1094.0099210214769;1098.3958822647512;1098.8351747237898;1101.6309536305348;797.2824457987799;799.564237490569;800.4076983032572;801.8075661841116;801.8276070644839;19535.902320488447;19790.70313945292;19800.80199198004;19829;20363.08366123528;20692.79307206924;21926.342209733735;21962;22424.66363004556;22428;16098.436554720529;16379.836201638069;16428.917855410768;16435.506934791974;16596.58508537284;3630;3667.5965643779164;3668.413053911361;3670.3209906167444;3670.743047986642;124970;126747.36626316901;127703.36148319292;128182.35908820489;129943;17190.74213886793;17317.307307707804;17506.64986700259;17530.824691753176;17549.824501755076;30204.84897575498;30291.091267262018;30810.845945770576;31893.681063189535;32105.51841722376;7630.504017238862;7931.881021784678;8062.798430039296;8322.417430779788;8606.655733770704;4183.45615070045;4303.397524346594;4310.31035034393;4313.223619748413;4316.525182229952;5257.737113144328;5302.867428314323;5341.759620816995;5362.731863406814;5382.4886635769535;1052.9578816847393;1060.5545670818274;1063.5586231713753;1074.4788777442934;1075.3279200499687;672.143017652497;683.6923384477005;691.350130876974;699.3845416033857;704.3519961635308;11552.995322694289;11662.475188616532;12229.205101668364;12390.194637348544;12839.358032098544;698.0715648187897;699.5697645947705;704.1972151747012;712.4051417066808;713.5290708132711;5541.695206763667;5748.109043098243;5795;5845.883082338414;5908.881822363529;110629;112734;114127.42936285431;114563;115652.42173789078;97318.5134074325;97618;99696.50455243177;100170;100783.49608251912;86930;87646.99999999872;88359.55820220857;91384.08615913888;91798.08201917895;10537.367757934548;12036.939815300868;12531;12650.493980240688;12665.303408312631;38035.239295214495;38611.806940965114;39234.803825980685;40307.79846100751;41822.790886045375;71096.28903710896;71573;72244.27755722376;72805.63597181981;73678;16137.75793363124;18472.690913635797;18505.074746262362;18507.35224267144;18552.907235463736;18729.344472943383;20362.694559581916;21138.365849024554;21200.893995529925;21624;17710.380136695152;18275.634487309915;18348.17433215535;18449.723254151468;18499.445016649523;15875.444359447363;17651.911740441217;17824.643507130044;17829.910850445664;18043.278268869097;17977.10114494296;18009.909950450165;18246.72629910526;18361.54096147607;18791.34230301933;13428.664283392993;13518.93240533791;13611.387487562886;13923.164610123426;13926.025178237425;13978.580642580739;14070.085444446078;14157.575272741835;14204.431822726976;15071.020383675026 15:35:01 INFO - PID 12427 | __end_tp_report 15:35:01 INFO - PID 12427 | __start_cc_report 15:35:01 INFO - PID 12427 | _x_x_mozilla_cycle_collect,368 15:35:01 INFO - PID 12427 | __end_cc_report 15:35:01 INFO - PID 12427 | __startTimestamp1502836501042__endTimestamp 15:35:01 INFO - PID 12427 | 15:35:01 INFO - PID 12427 | ------- Summary: start ------- 15:35:01 INFO - PID 12427 | Number of tests: 6 15:35:01 INFO - PID 12427 | 15:35:01 INFO - PID 12427 | [#0] dojo.html Cycles:165 Average:6073.17 Median:6310.87 stddev:727.47 (11.5%) stddev-sans-first:691.58 15:35:01 INFO - PID 12427 | Values: 3110.8 3151.7 3152.0 3163.6 3205.8 6078.4 6244.0 6247.0 6251.2 6290.1 6022.5 6139.3 6140.4 6145.0 6162.6 5566.0 5582.1 5616.2 5620.7 5627.2 5571.2 5652.6 5692.3 5746.9 5768.3 5618.7 5703.1 5703.2 5704.6 5729.0 5466.3 5536.5 5552.2 5648.0 5715.8 5533.0 5556.9 5560.3 5584.9 5603.7 5871.8 6011.2 6063.2 6110.0 6121.7 6540.9 6586.6 6604.8 6625.8 6662.6 6716.4 6735.4 6758.2 6779.9 6785.7 6156.7 6169.0 6235.7 6239.2 6253.7 6678.6 6811.1 6902.6 6911.8 6915.3 6468.7 6473.0 6566.9 6572.7 6573.0 6548.9 6610.9 6611.1 6624.1 6625.1 6485.1 6500.5 6515.0 6572.2 6600.9 6471.0 6535.3 6567.8 6624.8 6629.8 5252.5 5255.5 5256.4 5261.8 5263.8 4517.3 4535.8 4537.8 4541.1 4542.1 5602.7 5611.9 5624.4 5627.9 5702.1 6530.1 6589.2 6595.2 6606.7 6646.2 5626.7 5648.2 5702.9 5721.5 5734.8 5741.7 5752.1 5798.3 5817.4 5820.4 6200.3 6288.5 6386.4 6441.2 6490.5 6724.4 6726.5 6758.7 6763.2 6779.2 6393.8 6419.6 6487.6 6506.4 6544.7 6234.6 6492.2 6550.2 6581.7 6618.9 6561.5 6565.0 6642.0 6645.8 6651.9 6412.8 6418.5 6453.4 6459.5 6495.1 6384.8 6396.0 6492.0 6495.3 6507.0 6189.4 6334.7 6337.4 6431.3 6436.2 6355.7 6381.2 6417.3 6419.4 6422.6 6212.7 6235.1 6246.2 6310.9 6320.7 15:35:01 INFO - PID 12427 | 15:35:01 INFO - PID 12427 | [#1] ext.html Cycles:180 Average:37731.78 Median:35397.06 stddev:52244.28 (147.6%) stddev-sans-first:52317.30 15:35:01 INFO - PID 12427 | Values: 816.6 841.8 870.5 883.6 1154.6 31533.5 32274.0 42655.4 43356.0 43408.6 42808.6 42925.0 42949.4 43487.6 43551.0 7504.4 7653.3 7655.7 7662.9 7713.2 7561.6 7627.6 7662.8 7666.6 7753.1 7630.5 7671.2 7764.6 7776.7 7795.1 7649.1 7673.1 7678.0 7678.3 7680.4 7588.4 7628.0 7653.5 7669.6 7674.0 38312.2 38422.4 38580.8 38672.2 38875.4 3497.7 3530.7 3533.1 3541.7 3544.1 31881.0 31932.7 32024.5 32058.0 32086.0 5000.9 5019.5 5028.7 5038.6 5067.0 179015.0 180807.0 181107.0 181270.0 181376.0 48805.3 48818.8 48918.0 48920.0 49117.5 51016.0 51313.0 51388.0 51450.7 51537.0 14543.6 14805.6 16414.9 16881.5 16894.2 12460.7 12542.1 12569.4 12570.7 12605.2 14819.6 14843.2 14952.3 14961.9 15007.0 5910.0 6089.1 6089.5 6102.9 6114.3 1001.8 1019.9 1021.3 1022.4 1029.3 23152.9 23255.3 23332.4 23418.3 23527.8 1106.8 1108.7 1119.4 1132.2 1132.5 12334.6 12338.0 12341.8 12413.1 12509.0 230013.0 231720.0 231763.0 231879.0 233438.0 53740.7 54288.2 54339.0 54423.7 54473.0 1458.6 1468.3 1479.3 1497.5 1524.0 23116.3 23202.8 23298.9 23307.2 23437.3 37792.2 37826.0 37892.6 37912.6 37939.4 188582.0 188673.0 188849.0 188976.0 189122.0 35306.0 36545.6 38910.2 39081.8 39374.8 46080.3 48274.5 48289.3 48471.8 48982.0 34865.0 35002.3 35025.6 35395.1 35399.0 34271.3 34275.8 34281.7 35147.3 35238.5 37576.0 37594.4 37608.8 37890.6 38004.6 33436.0 33805.3 33884.2 34046.3 34288.0 24935.5 25069.4 25113.0 25263.9 25364.9 15:35:01 INFO - PID 12427 | 15:35:01 INFO - PID 12427 | [#2] jquery.html Cycles:180 Average:34591.57 Median:12975.24 stddev:80710.42 (622.0%) stddev-sans-first:80896.43 15:35:01 INFO - PID 12427 | Values: 574.8 577.9 580.9 581.8 593.9 22453.6 22767.3 23685.4 23721.4 23731.2 24968.3 26672.0 26691.5 26696.0 26711.7 6627.1 6774.5 6784.0 6784.9 6786.1 6041.2 6582.6 6587.3 6617.5 6678.5 6527.7 6529.8 6536.5 6536.8 6537.9 6501.2 6528.8 6530.0 6546.7 6547.8 5994.5 6057.1 6058.3 6082.0 6099.8 28681.9 29437.9 30030.0 30928.8 31044.5 1919.1 1962.9 1962.9 1963.4 1965.6 19816.8 20304.3 20418.4 20434.8 20476.0 2300.6 2345.9 2347.6 2365.0 2472.5 138817.0 140124.0 142242.0 142632.0 143045.0 14039.8 14733.0 15143.2 15192.2 15340.0 23725.8 23906.9 23912.8 23946.2 23960.8 9838.3 9890.8 9910.9 9965.7 10009.2 8997.4 9027.3 9031.0 9081.6 9173.9 7934.6 9226.3 9340.5 9470.7 9605.5 2735.2 2759.6 2767.1 2805.8 2828.7 961.5 974.6 975.1 998.7 1000.1 19553.9 19558.0 19625.4 19847.8 23585.5 992.7 1061.9 1064.4 1068.4 1081.3 11170.6 11218.8 11220.9 11291.2 11292.6 450911.0 464402.0 465923.0 475519.0 521471.0 117072.4 119841.0 119885.0 119983.0 123323.0 1251.4 1272.2 1287.7 1288.0 1294.5 17421.5 17523.0 17699.5 17910.1 18076.7 52816.0 52969.5 53310.5 53848.7 54926.0 65805.7 73983.0 74296.6 74398.3 75263.9 14428.1 14775.2 14813.3 14838.2 14857.6 22823.9 23016.8 23058.7 23215.4 23232.7 16889.2 16995.3 17018.1 17607.7 17836.7 16696.0 16795.4 17185.1 17263.1 17361.9 16875.7 17349.8 18465.9 18913.6 18985.4 14457.9 14688.2 14732.0 14761.9 14766.2 16864.7 17057.3 17099.5 17469.2 18545.3 15:35:01 INFO - PID 12427 | 15:35:01 INFO - PID 12427 | [#3] mootools.html Cycles:180 Average:9074.40 Median:2639.62 stddev:6272.54 (237.6%) stddev-sans-first:6263.16 15:35:01 INFO - PID 12427 | Values: 1332.0 1368.0 1403.9 1415.2 1416.6 14091.2 14319.0 14758.6 14832.1 14846.6 14888.5 15193.8 15213.6 15240.9 15245.5 2648.4 2708.9 2709.5 2712.4 2713.1 2613.3 2809.9 2810.8 2811.1 2813.4 2677.4 2686.7 2710.3 2713.6 2716.6 2656.4 2678.1 2692.8 2727.5 2727.9 2630.9 2695.6 2711.8 2713.0 2713.1 14149.6 14259.9 14417.8 14419.5 14428.9 7572.7 7882.9 7917.6 7921.8 7931.2 4945.9 5135.0 5276.6 5310.7 5311.3 1809.8 1842.5 1888.0 1909.5 1926.9 24981.8 25201.6 25227.7 25249.9 25314.6 6590.9 6968.5 7174.8 7178.4 7233.0 16318.8 16398.3 16442.1 16478.1 16493.9 7929.1 8212.3 8216.4 8234.7 8248.6 6153.5 7119.8 7217.6 7322.2 7327.5 4740.8 4933.5 5047.3 5205.2 5274.8 1510.6 1515.6 1529.4 1535.3 1540.9 905.2 915.7 917.8 925.8 928.3 8056.8 8397.0 8511.3 8526.2 8542.0 999.6 1007.0 1020.4 1020.9 1022.9 5318.4 5447.2 5461.1 5713.5 5747.4 19694.2 20283.9 20344.3 20436.2 20437.7 19225.2 19977.5 20035.7 20075.5 20384.3 10309.8 10407.6 10486.0 10491.5 10534.8 8211.6 8440.1 8746.8 8967.0 9115.3 17645.7 17731.6 17912.6 18239.7 18520.2 17829.5 17841.9 17952.8 18017.5 18123.5 10744.7 11380.0 11762.7 11789.3 11794.0 12207.8 13249.0 13589.1 13599.5 13676.7 11125.4 11261.3 11287.7 11405.0 11511.3 10768.9 10790.1 10940.5 10999.8 11150.5 9302.0 9477.7 9539.4 9542.1 9559.0 7405.3 7416.1 7420.4 7471.9 7475.3 7208.5 7213.0 7244.2 7258.7 7327.9 15:35:01 INFO - PID 12427 | 15:35:01 INFO - PID 12427 | [#4] prototype.html Cycles:180 Average:5446.52 Median:6091.09 stddev:1546.57 (25.4%) stddev-sans-first:1505.41 15:35:01 INFO - PID 12427 | Values: 485.3 493.3 514.1 520.2 522.2 6167.0 6171.7 6193.0 6198.0 6246.3 5909.4 6094.9 6138.9 6176.6 6189.8 4770.4 4885.7 4923.7 4944.1 4976.7 4922.9 4954.8 4968.7 4979.9 5004.3 4728.3 4879.7 4880.4 4885.9 4890.3 4879.0 4884.6 4888.0 4895.3 4900.1 4353.7 4363.6 4373.3 4388.6 4410.1 6050.1 6072.4 6075.6 6088.6 6093.5 5618.6 5626.9 5640.5 5669.8 5675.5 6428.7 6488.3 6499.7 6500.7 6537.2 4722.2 4750.3 4753.0 4768.3 4777.0 6739.4 6804.8 6836.1 6888.9 6899.9 4830.4 4831.2 4872.2 4888.7 4904.6 4929.9 4937.1 4941.2 4967.7 4972.8 5572.2 5582.6 5592.6 5623.7 5638.2 6445.5 6481.2 6486.6 6552.6 6572.0 1619.8 1693.9 1722.7 1734.5 1738.2 1132.2 1158.5 1163.4 1171.4 1194.5 5592.7 5635.1 5687.1 5706.0 5766.2 6453.7 6529.0 6628.3 6662.6 6684.4 5665.5 5710.6 5719.8 5761.7 5786.8 3499.6 3501.9 3510.2 3560.2 3583.3 6415.8 6428.5 6439.0 6486.5 6510.8 6844.7 6861.5 6871.2 6871.9 6907.5 6389.3 6409.8 6452.2 6468.0 6484.4 6579.1 6595.6 6708.6 6711.4 6737.8 6579.7 6627.6 6639.1 6693.0 6693.6 6464.5 6468.3 6539.9 6549.2 6553.8 4809.6 4824.1 4830.9 4832.7 4877.3 6628.5 6635.9 6647.4 6681.4 6728.8 6413.2 6422.8 6470.2 6504.8 6525.9 6246.8 6396.5 6422.9 6484.1 6573.3 6073.7 6253.0 6302.4 6363.3 6380.3 6501.6 6529.1 6534.3 6536.5 6541.8 6361.5 6364.8 6373.8 6375.8 6385.7 15:35:01 INFO - PID 12427 | 15:35:01 INFO - PID 12427 | [#5] yui.html Cycles:180 Average:24162.85 Median:19629.51 stddev:32826.73 (167.2%) stddev-sans-first:32872.37 15:35:01 INFO - PID 12427 | Values: 907.0 909.2 932.5 936.4 942.0 18708.9 19601.6 19657.4 19671.7 21333.3 21073.4 21777.3 21893.6 21922.6 21938.1 1036.2 1046.0 1048.8 1049.0 1049.6 1022.7 1047.8 1055.5 1056.0 1059.9 1095.7 1100.1 1100.2 1100.8 1100.9 1091.6 1094.0 1098.4 1098.8 1101.6 797.3 799.6 800.4 801.8 801.8 19535.9 19790.7 19800.8 19829.0 20363.1 20692.8 21926.3 21962.0 22424.7 22428.0 16098.4 16379.8 16428.9 16435.5 16596.6 3630.0 3667.6 3668.4 3670.3 3670.7 124970.0 126747.4 127703.4 128182.4 129943.0 17190.7 17317.3 17506.6 17530.8 17549.8 30204.8 30291.1 30810.8 31893.7 32105.5 7630.5 7931.9 8062.8 8322.4 8606.7 4183.5 4303.4 4310.3 4313.2 4316.5 5257.7 5302.9 5341.8 5362.7 5382.5 1053.0 1060.6 1063.6 1074.5 1075.3 672.1 683.7 691.4 699.4 704.4 11553.0 11662.5 12229.2 12390.2 12839.4 698.1 699.6 704.2 712.4 713.5 5541.7 5748.1 5795.0 5845.9 5908.9 110629.0 112734.0 114127.4 114563.0 115652.4 97318.5 97618.0 99696.5 100170.0 100783.5 86930.0 87647.0 88359.6 91384.1 91798.1 10537.4 12036.9 12531.0 12650.5 12665.3 38035.2 38611.8 39234.8 40307.8 41822.8 71096.3 71573.0 72244.3 72805.6 73678.0 16137.8 18472.7 18505.1 18507.4 18552.9 18729.3 20362.7 21138.4 21200.9 21624.0 17710.4 18275.6 18348.2 18449.7 18499.4 15875.4 17651.9 17824.6 17829.9 18043.3 17977.1 18009.9 18246.7 18361.5 18791.3 13428.7 13518.9 13611.4 13923.2 13926.0 13978.6 14070.1 14157.6 14204.4 15071.0 15:35:01 INFO - PID 12427 | -------- Summary: end -------- 15:35:01 INFO - PID 12427 | 15:35:01 INFO - TEST-INFO | 12427: exit 0 15:35:01 INFO - TEST-OK | dromaeo_css | took 1111667ms 15:35:01 INFO - TEST-START | kraken 15:35:01 INFO - Initialising browser for kraken test... 15:35:01 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:56348/getInfo.html -profile /tmp/tmpNMJFgW/profile 15:35:01 INFO - TEST-INFO | started process 12813 (/builds/slave/test/build/application/firefox/firefox http://localhost:56348/getInfo.html) 15:35:07 INFO - TEST-INFO | 12813: exit 0 15:35:07 INFO - Browser initialized. 15:35:07 INFO - Running cycle 1/1 for kraken test... 15:35:07 INFO - TEST-INFO | started process 13051 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNMJFgW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 15:35:08 INFO - PID 13051 | 15:35:08 INFO - PID 13051 | (/builds/slave/test/build/application/firefox/firefox:13106): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:35:08 INFO - PID 13051 | 15:35:18 INFO - PID 13051 | RSS: Main: 166883328 15:35:18 INFO - PID 13051 | 15:35:21 INFO - PID 13051 | Cycle 1(1): loaded http://localhost:56348/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56348/tests/kraken/driver.html?testName=audio-beat-detection) 15:35:22 INFO - PID 13051 | RSS: Main: 173223936 15:35:22 INFO - PID 13051 | 15:35:25 INFO - PID 13051 | Cycle 1(1): loaded http://localhost:56348/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56348/tests/kraken/driver.html?testName=audio-dft) 15:35:25 INFO - PID 13051 | RSS: Main: 177033216 15:35:25 INFO - PID 13051 | 15:35:28 INFO - PID 13051 | Cycle 1(1): loaded http://localhost:56348/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56348/tests/kraken/driver.html?testName=audio-fft) 15:35:29 INFO - PID 13051 | RSS: Main: 171900928 15:35:29 INFO - PID 13051 | 15:35:31 INFO - PID 13051 | Cycle 1(1): loaded http://localhost:56348/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56348/tests/kraken/driver.html?testName=audio-oscillator) 15:35:32 INFO - PID 13051 | RSS: Main: 171667456 15:35:32 INFO - PID 13051 | 15:35:34 INFO - PID 13051 | Cycle 1(1): loaded http://localhost:56348/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56348/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:35:35 INFO - PID 13051 | RSS: Main: 171855872 15:35:35 INFO - PID 13051 | 15:35:38 INFO - PID 13051 | Cycle 1(1): loaded http://localhost:56348/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56348/tests/kraken/driver.html?testName=imaging-darkroom) 15:35:38 INFO - PID 13051 | RSS: Main: 173072384 15:35:38 INFO - PID 13051 | 15:35:42 INFO - PID 13051 | Cycle 1(1): loaded http://localhost:56348/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56348/tests/kraken/driver.html?testName=imaging-desaturate) 15:35:43 INFO - PID 13051 | RSS: Main: 172511232 15:35:43 INFO - PID 13051 | 15:35:46 INFO - PID 13051 | Cycle 1(1): loaded http://localhost:56348/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56348/tests/kraken/driver.html?testName=json-parse-financial) 15:35:46 INFO - PID 13051 | RSS: Main: 173572096 15:35:46 INFO - PID 13051 | 15:35:48 INFO - PID 13051 | Cycle 1(1): loaded http://localhost:56348/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56348/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:35:48 INFO - PID 13051 | RSS: Main: 173580288 15:35:48 INFO - PID 13051 | 15:35:50 INFO - PID 13051 | Cycle 1(1): loaded http://localhost:56348/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56348/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:35:51 INFO - PID 13051 | RSS: Main: 173846528 15:35:51 INFO - PID 13051 | 15:35:53 INFO - PID 13051 | Cycle 1(1): loaded http://localhost:56348/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56348/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:35:54 INFO - PID 13051 | RSS: Main: 173301760 15:35:54 INFO - PID 13051 | 15:35:57 INFO - PID 13051 | Cycle 1(1): loaded http://localhost:56348/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56348/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:35:57 INFO - PID 13051 | RSS: Main: 174501888 15:35:57 INFO - PID 13051 | 15:36:00 INFO - PID 13051 | Cycle 1(1): loaded http://localhost:56348/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56348/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:36:01 INFO - PID 13051 | RSS: Main: 174100480 15:36:01 INFO - PID 13051 | 15:36:03 INFO - PID 13051 | Cycle 1(1): loaded http://localhost:56348/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56348/tests/kraken/driver.html?testName=ai-astar) 15:36:03 INFO - PID 13051 | RSS: Main: 175386624 15:36:03 INFO - PID 13051 | 15:36:03 INFO - PID 13051 | __start_tp_report 15:36:03 INFO - PID 13051 | _x_x_mozilla_page_load 15:36:03 INFO - PID 13051 | _x_x_mozilla_page_load_details 15:36:03 INFO - PID 13051 | |i|pagename|runs| 15:36:03 INFO - PID 13051 | |0;ai-astar;107;108;101;112;109;109;110;100;110;111 15:36:03 INFO - PID 13051 | |1;audio-beat-detection;156;117;140;175;116;153;141;118;175;127 15:36:03 INFO - PID 13051 | |2;audio-dft;164;162;163;170;172;162;165;167;168;165 15:36:03 INFO - PID 13051 | |3;audio-fft;75;77;76;168;77;76;76;75;153;79 15:36:03 INFO - PID 13051 | |4;audio-oscillator;79;78;137;79;79;79;135;79;79;128 15:36:03 INFO - PID 13051 | |5;imaging-gaussian-blur;113;114;115;115;114;121;114;120;123;115 15:36:03 INFO - PID 13051 | |6;imaging-darkroom;153;150;151;150;152;150;154;152;151;153 15:36:03 INFO - PID 13051 | |7;imaging-desaturate;99;99;96;95;94;96;94;94;94;93 15:36:03 INFO - PID 13051 | |8;json-parse-financial;53;53;53;53;54;53;54;59;67;54 15:36:03 INFO - PID 13051 | |9;json-stringify-tinderbox;60;61;56;55;63;55;55;57;61;61 15:36:03 INFO - PID 13051 | |10;stanford-crypto-aes;82;89;78;80;85;78;79;86;90;84 15:36:03 INFO - PID 13051 | |11;stanford-crypto-ccm;137;130;131;131;128;125;124;126;122;171 15:36:03 INFO - PID 13051 | |12;stanford-crypto-pbkdf2;180;180;178;184;182;180;174;188;181;174 15:36:03 INFO - PID 13051 | |13;stanford-crypto-sha256-iterative;67;73;68;68;72;67;68;72;73;69 15:36:03 INFO - PID 13051 | __end_tp_report 15:36:03 INFO - PID 13051 | __start_cc_report 15:36:03 INFO - PID 13051 | _x_x_mozilla_cycle_collect,945 15:36:03 INFO - PID 13051 | __end_cc_report 15:36:03 INFO - PID 13051 | __startTimestamp1502836563641__endTimestamp 15:36:03 INFO - PID 13051 | 15:36:03 INFO - PID 13051 | ------- Summary: start ------- 15:36:03 INFO - PID 13051 | Number of tests: 14 15:36:03 INFO - PID 13051 | 15:36:03 INFO - PID 13051 | [#0] ai-astar Cycles:10 Average:107.70 Median:109.50 stddev:4.06 (3.7%) stddev-sans-first:4.29 15:36:03 INFO - PID 13051 | Values: 107.0 108.0 101.0 112.0 109.0 109.0 110.0 100.0 110.0 111.0 15:36:03 INFO - PID 13051 | 15:36:03 INFO - PID 13051 | [#1] audio-beat-detection Cycles:10 Average:141.80 Median:147.00 stddev:22.61 (15.4%) stddev-sans-first:23.39 15:36:03 INFO - PID 13051 | Values: 156.0 117.0 140.0 175.0 116.0 153.0 141.0 118.0 175.0 127.0 15:36:03 INFO - PID 13051 | 15:36:03 INFO - PID 13051 | [#2] audio-dft Cycles:10 Average:165.80 Median:166.00 stddev:3.39 (2.0%) stddev-sans-first:3.54 15:36:03 INFO - PID 13051 | Values: 164.0 162.0 163.0 170.0 172.0 162.0 165.0 167.0 168.0 165.0 15:36:03 INFO - PID 13051 | 15:36:03 INFO - PID 13051 | [#3] audio-fft Cycles:10 Average:93.20 Median:76.00 stddev:35.66 (46.9%) stddev-sans-first:37.21 15:36:03 INFO - PID 13051 | Values: 75.0 77.0 76.0 168.0 77.0 76.0 76.0 75.0 153.0 79.0 15:36:03 INFO - PID 13051 | 15:36:03 INFO - PID 13051 | [#4] audio-oscillator Cycles:10 Average:95.20 Median:79.00 stddev:26.41 (33.4%) stddev-sans-first:27.35 15:36:03 INFO - PID 13051 | Values: 79.0 78.0 137.0 79.0 79.0 79.0 135.0 79.0 79.0 128.0 15:36:03 INFO - PID 13051 | 15:36:03 INFO - PID 13051 | [#5] imaging-gaussian-blur Cycles:10 Average:116.40 Median:115.00 stddev:3.53 (3.1%) stddev-sans-first:3.53 15:36:03 INFO - PID 13051 | Values: 113.0 114.0 115.0 115.0 114.0 121.0 114.0 120.0 123.0 115.0 15:36:03 INFO - PID 13051 | 15:36:03 INFO - PID 13051 | [#6] imaging-darkroom Cycles:10 Average:151.60 Median:152.00 stddev:1.43 (0.9%) stddev-sans-first:1.42 15:36:03 INFO - PID 13051 | Values: 153.0 150.0 151.0 150.0 152.0 150.0 154.0 152.0 151.0 153.0 15:36:03 INFO - PID 13051 | 15:36:03 INFO - PID 13051 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:2.12 (2.2%) stddev-sans-first:1.80 15:36:03 INFO - PID 13051 | Values: 99.0 99.0 96.0 95.0 94.0 96.0 94.0 94.0 94.0 93.0 15:36:03 INFO - PID 13051 | 15:36:03 INFO - PID 13051 | [#8] json-parse-financial Cycles:10 Average:55.30 Median:54.00 stddev:4.50 (8.3%) stddev-sans-first:4.69 15:36:03 INFO - PID 13051 | Values: 53.0 53.0 53.0 53.0 54.0 53.0 54.0 59.0 67.0 54.0 15:36:03 INFO - PID 13051 | 15:36:03 INFO - PID 13051 | [#9] json-stringify-tinderbox Cycles:10 Average:58.40 Median:60.50 stddev:3.10 (5.1%) stddev-sans-first:3.23 15:36:03 INFO - PID 13051 | Values: 60.0 61.0 56.0 55.0 63.0 55.0 55.0 57.0 61.0 61.0 15:36:03 INFO - PID 13051 | 15:36:03 INFO - PID 13051 | [#10] stanford-crypto-aes Cycles:10 Average:83.10 Median:84.50 stddev:4.41 (5.2%) stddev-sans-first:4.66 15:36:03 INFO - PID 13051 | Values: 82.0 89.0 78.0 80.0 85.0 78.0 79.0 86.0 90.0 84.0 15:36:03 INFO - PID 13051 | 15:36:03 INFO - PID 13051 | [#11] stanford-crypto-ccm Cycles:10 Average:132.50 Median:130.50 stddev:14.20 (10.9%) stddev-sans-first:14.97 15:36:03 INFO - PID 13051 | Values: 137.0 130.0 131.0 131.0 128.0 125.0 124.0 126.0 122.0 171.0 15:36:03 INFO - PID 13051 | 15:36:03 INFO - PID 13051 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.10 Median:180.50 stddev:4.23 (2.3%) stddev-sans-first:4.48 15:36:03 INFO - PID 13051 | Values: 180.0 180.0 178.0 184.0 182.0 180.0 174.0 188.0 181.0 174.0 15:36:03 INFO - PID 13051 | 15:36:03 INFO - PID 13051 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.70 Median:70.50 stddev:2.50 (3.5%) stddev-sans-first:2.45 15:36:03 INFO - PID 13051 | Values: 67.0 73.0 68.0 68.0 72.0 67.0 68.0 72.0 73.0 69.0 15:36:03 INFO - PID 13051 | -------- Summary: end -------- 15:36:03 INFO - PID 13051 | 15:36:04 INFO - PID 13051 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 15:36:04 INFO - TEST-INFO | 13051: exit 0 15:36:04 INFO - TEST-OK | kraken | took 62628ms 15:36:04 INFO - SUITE-END | took 1174s 15:36:04 INFO - Completed test suite (00:19:34) 15:36:04 INFO - javascript benchmark 15:36:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8536.063816886159, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3110.7511399088075, 3151.7005884441, 3151.9527207091896, 3163.58873346465, 3205.8236796976157, 6078.422549857767, 6244.000959846426, 6246.968765156179, 6251.187345645066, 6290.119383286344, 6022.548308876841, 6139.293981192164, 6140.416660417264, 6145.047517634774, 6162.568620196588, 5566.0, 5582.051051321275, 5616.157576363557, 5620.662760234379, 5627.212190293362, 5571.164325351189, 5652.576056795736, 5692.345380281258, 5746.885062298751, 5768.250127483421, 5618.747156377973, 5703.116017017348, 5703.201551782754, 5704.572157088235, 5729.026065568843, 5466.289382380265, 5536.529395001429, 5552.194931734876, 5648.03983322834, 5715.828525144252, 5532.972335138298, 5556.86107847303, 5560.304961879765, 5584.944150558483, 5603.7478313475585, 5871.823845284605, 6011.218541589566, 6063.18147050147, 6110.022396416552, 6121.693915304217, 6540.9345906541275, 6586.604803711744, 6604.768833090819, 6625.834354141137, 6662.600243985376, 6716.361945615159, 6735.360140786666, 6758.222804377461, 6779.898301525481, 6785.660716964181, 6156.722947467375, 6169.01295792671, 6235.719392627298, 6239.157713708637, 6253.749850005995, 6678.632675202911, 6811.148606424197, 6902.6203558803745, 6911.827204319831, 6915.308469153045, 6468.708908099147, 6473.029045643097, 6566.934330656728, 6572.737090516326, 6573.0, 6548.9017664735065, 6610.933890661128, 6611.140551728341, 6624.072629831828, 6625.105610742585, 6485.059666348353, 6500.512461565401, 6515.02274658805, 6572.178477690289, 6600.900986485207, 6471.029345598198, 6535.281119076898, 6567.802965911031, 6624.801255962232, 6629.7679581214425, 5252.474752524793, 5255.474452554714, 5256.39551451588, 5261.842144735664, 5263.789448422097, 4517.299818528133, 4535.818567257339, 4537.795799189044, 4541.091781643684, 4542.0915816836105, 5602.747876345574, 5611.9438805612235, 5624.3813180550105, 5627.943720562742, 5702.116171993348, 6530.05314229434, 6589.17635295588, 6595.175603049619, 6606.735730570819, 6646.235682896432, 5626.662400255997, 5648.209250704905, 5702.885942281131, 5721.456461636138, 5734.770609175586, 5741.712914354265, 5752.050911599539, 5798.333191682926, 5817.418258174149, 5820.35976042635, 6200.255969283715, 6288.496920246369, 6386.36136386367, 6441.227052753606, 6490.545661803723, 6724.4284235840505, 6726.529142959946, 6758.662066896733, 6763.188417389944, 6779.220389655055, 6393.840153996187, 6419.582727122722, 6487.610743355225, 6506.3818937200895, 6544.705488252945, 6234.625922444487, 6492.156019717407, 6550.181227346582, 6581.736730530725, 6618.933810661832, 6561.540692151503, 6564.9671751640935, 6642.036904648702, 6645.800625981228, 6651.866962660622, 6412.839679008062, 6418.454431373386, 6453.419192272718, 6459.515536334888, 6495.090687303876, 6384.776532821329, 6395.968020160056, 6492.0, 6495.285518593046, 6507.0, 6189.381061893955, 6334.651594162366, 6337.397947195009, 6431.324710905287, 6436.227652681709, 6355.682215889095, 6381.234251889803, 6417.2620148681635, 6419.358064193543, 6422.550421470639, 6212.689365531615, 6235.127082208585, 6246.156768836149, 6310.873782524415, 6320.715567799368], "value": 6018.584097753655, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [816.6080281464897, 841.7727213652314, 870.4777133719767, 883.6156272021672, 1154.6189757380066, 31533.526997095032, 32274.0, 42655.36016959744, 43356.0, 43408.56591434085, 42808.571914280845, 42925.0, 42949.35575966363, 43487.565124348825, 43551.0, 7504.399648028145, 7653.272939070779, 7655.732049378273, 7662.885056724153, 7713.1515533291295, 7561.62191890406, 7627.618619069052, 7662.808429789244, 7666.578338191397, 7753.147153813076, 7630.50401723889, 7671.156172820985, 7764.611769411535, 7776.65005074773, 7795.14253432122, 7649.120351159604, 7673.117591476968, 7678.001859758224, 7678.270564296383, 7680.423968202407, 7588.354989825872, 7627.961860190719, 7653.502522336031, 7669.616519174075, 7673.9616301918695, 38312.233755324734, 38422.423663645066, 38580.80709596434, 38672.22655546873, 38875.416868746994, 3497.720182385403, 3530.735194860396, 3533.116720819795, 3541.7166626669805, 3544.113971507123, 31881.0, 31932.680673193205, 32024.519632205534, 32058.0, 32086.0, 5000.89998200034, 5019.498050194988, 5028.723420211887, 5038.5969122470115, 5066.9746651266505, 179015.0, 180807.0, 181107.0, 181270.0, 181376.0, 48805.26792098121, 48818.75590622024, 48918.0, 48920.0, 49117.508824911296, 51016.0, 51313.0, 51388.0, 51450.74274628603, 51537.0, 14543.636409089857, 14805.555833325017, 16414.917925410296, 16881.493555193363, 16894.239759210865, 12460.688482787822, 12542.122051456492, 12569.371531423392, 12570.68573285657, 12605.243685378724, 14819.629509262139, 14843.183624900517, 14952.327145278488, 14961.925190373979, 15007.0, 5910.0, 6089.117078023662, 6089.512838972872, 6102.908456373158, 6114.266288045463, 1001.8146642871092, 1019.8623185869812, 1021.3055122516612, 1022.3508072374096, 1029.2537910015178, 23152.88423557905, 23255.3023409298, 23332.416689582897, 23418.297451076494, 23527.7647223529, 1106.778644271149, 1108.711734948919, 1119.3731510354228, 1132.2074547816562, 1132.4903793293051, 12334.629961101182, 12338.0, 12341.814872776915, 12413.06901982355, 12509.0, 230013.0, 231720.0, 231763.0, 231879.0, 233438.0, 53740.73129634405, 54288.185677214875, 54339.0, 54423.72788136034, 54473.0, 1458.6207586027497, 1468.3392473387019, 1479.275155173957, 1497.52827859224, 1523.9619009524852, 23116.3065108047, 23202.767972320737, 23298.883505582362, 23307.18424855122, 23437.296881093593, 37792.244155117296, 37826.0, 37892.62107379001, 37912.62087379091, 37939.430908536946, 188582.0, 188673.0, 188849.0, 188976.0, 189122.0, 35306.0, 36545.634543654225, 38910.221795564496, 39081.804590978, 39374.80312598419, 46080.308795367426, 48274.517254827006, 48289.27566086441, 48471.75764121157, 48982.0, 34865.0, 35002.29995400027, 35025.64974350326, 35395.11512212215, 35399.0, 34271.31457370789, 34275.82862085674, 34281.65718342884, 35147.29705405826, 35238.47142292817, 37576.0, 37594.436083458226, 37608.811955940044, 37890.62109378871, 38004.61995380011, 33436.0, 33805.3238935215, 33884.152896177795, 34046.319073618884, 34288.0, 24935.501289973738, 25069.373265668502, 25112.995480180587, 25263.863126159733, 25364.873175634002], "value": 17472.303660242782, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [574.7988204128555, 577.933537643171, 580.875111850952, 581.8429024163479, 593.9406059394062, 22453.55092898141, 22767.316980490592, 23685.40786480337, 23721.406964825866, 23731.169409070684, 24968.250952471408, 26672.0, 26691.466170676576, 26696.0, 26711.732882671167, 6627.072209890631, 6774.5257831951785, 6784.0, 6784.9321506785045, 6786.083878676365, 6041.214642096521, 6582.572132811376, 6587.275399706028, 6617.503687223453, 6678.465722742193, 6527.738890444401, 6529.771457998971, 6536.4770818334655, 6536.836579085513, 6537.869242615145, 6501.154849869534, 6528.771492997722, 6530.0, 6546.738130474751, 6547.803565892983, 5994.520438364921, 6057.121717351003, 6058.3032951210735, 6081.969590152065, 6099.847503812396, 28681.85659071712, 29437.85281073602, 30030.0, 30928.8453557733, 31044.53433198504, 1919.1076149590508, 1962.9214831406728, 1962.931297404584, 1963.352093809054, 1965.6167047425758, 19816.80183198164, 20304.289349872684, 20418.387448376572, 20434.795652043438, 20476.0, 2300.608896487601, 2345.9296221113395, 2347.5891718949115, 2364.9527009459716, 2472.4560596668703, 138817.0, 140124.0, 142242.0, 142632.0, 143044.99999999895, 14039.82382730658, 14733.0, 15143.167125807966, 15192.240387980557, 15340.002899811476, 23725.762742372357, 23906.880465597562, 23912.7608723914, 23946.161884333964, 23960.760392395852, 9838.311318207656, 9890.802183956424, 9910.900890991143, 9965.701028969142, 10009.249306302056, 8997.37018408705, 9027.322950778718, 9031.0, 9081.636734530677, 9173.862392064124, 7934.603269836485, 9226.30802689801, 9340.4862732549, 9470.668526601536, 9605.47169905648, 2735.2341344423594, 2759.599858020576, 2767.1283545683045, 2805.8456784877026, 2828.6747024092083, 961.4519723757531, 974.6004138303261, 975.1272611013245, 998.6654470752234, 1000.0849222961054, 19553.902230489042, 19558.0, 19625.411237662895, 19847.801521984886, 23585.52828943446, 992.6823416506793, 1061.9097376722912, 1064.3932958213898, 1068.3696618994675, 1081.2701426537055, 11170.609028683957, 11218.831717524243, 11220.887791122148, 11291.153163512769, 11292.604758833402, 450911.0, 464402.0, 465923.0, 475519.0, 521471.0, 117072.41463792627, 119841.0, 119885.0, 119983.0, 123323.0, 1251.443107817031, 1272.204871955028, 1287.7424515097018, 1287.9871201288054, 1294.4822071171418, 17421.47735567935, 17523.0, 17699.469015929542, 17910.104494774947, 18076.72884906754, 52816.0, 52969.470305296454, 53310.46689533055, 53848.73075634597, 54926.0, 65805.67097164484, 73983.0, 74296.62851685923, 74398.25601743913, 75263.8710419355, 14428.134311941318, 14775.187364695044, 14813.259337033322, 14838.18389988561, 14857.554273371816, 22823.885880571153, 23016.84915754172, 23058.654120187875, 23215.419614509774, 23232.651510227697, 16889.155542223085, 16995.32018719287, 17018.149092545074, 17607.735883961494, 17836.732449013536, 16696.0, 16795.41216057432, 17185.14074296255, 17263.13684315754, 17361.913190433967, 16875.74686379681, 17349.82650173482, 18465.90767046156, 18913.621727565096, 18985.43043708691, 14457.92771036138, 14688.192149431883, 14732.0, 14761.852381476478, 14766.187859667822, 16864.747028794332, 17057.317707292066, 17099.487015389557, 17469.213885374935, 18545.258189672262], "value": 12009.951675220023, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1332.0, 1367.9658008549784, 1403.9157650540963, 1415.179196066282, 1416.5962700630323, 14091.15453072815, 14319.0, 14758.557243282705, 14832.110073395605, 14846.628834279149, 14888.478903238389, 15193.848061519382, 15213.619659508518, 15240.923795381008, 15245.542633721006, 2648.3643925457945, 2708.945821083587, 2709.4716530276605, 2712.3625947902324, 2713.145359211852, 2613.2813476294077, 2809.9157025289273, 2810.8454035028067, 2811.11449893284, 2813.395120049189, 2677.4377380750066, 2686.717894621066, 2710.254679963016, 2713.579395193748, 2716.5925111233373, 2656.3890305229806, 2678.129607877438, 2692.7711144552736, 2727.481778462106, 2727.890884364623, 2630.88161032754, 2695.555233386489, 2711.769499592537, 2713.0, 2713.1046754570943, 14149.646258843612, 14259.857401426061, 14417.78373324401, 14419.495317663832, 14428.855711442857, 7572.697092116308, 7882.921170788276, 7917.643706033243, 7921.762347129596, 7931.206879312081, 4945.9258111128365, 5135.0, 5276.630635855492, 5310.734463276859, 5311.28297679816, 1809.8190180981928, 1842.456475339778, 1887.9899253899234, 1909.484439201408, 1926.8940208288539, 24981.750182498305, 25201.62197567038, 25227.747722522723, 25249.863756130835, 25314.620280695803, 6590.934090659128, 6968.512204145763, 7174.78475645721, 7178.425725941912, 7233.0, 16318.836811631969, 16398.26207820651, 16442.095684737214, 16478.09370484635, 16493.917530412273, 7929.1277959424415, 8212.34301255898, 8216.383771217183, 8234.670613175405, 8248.587570621445, 6153.507719382439, 7119.786406407816, 7217.566945983152, 7322.1945585985495, 7327.45044121693, 4740.786664600101, 4933.481984391659, 5047.343845300161, 5205.193195054772, 5274.841754747364, 1510.5543864559982, 1515.5756388211319, 1529.388244702128, 1535.293764868151, 1540.8998415103206, 905.2124651553191, 915.7390143808982, 917.7613820406609, 925.796324808541, 928.3037721708719, 8056.83886322282, 8397.0, 8511.319094472427, 8526.190011948855, 8541.95729021351, 999.6201443451442, 1007.0483393193508, 1020.3673722292078, 1020.8774947006258, 1022.9130523905402, 5318.361796584435, 5447.155690867842, 5461.126219804892, 5713.542916566665, 5747.425257474303, 19694.21223151058, 20283.8985805073, 20344.287949921978, 20436.182552698025, 20437.693434598492, 19225.230990760494, 19977.500562486053, 20035.699464508045, 20075.498112547302, 20384.28654997068, 10309.793804123876, 10407.63573274947, 10486.0, 10491.475426228812, 10534.841977370346, 8211.630476628685, 8440.113788052411, 8746.825063498822, 8966.955165224394, 9115.316351273814, 17645.735313970043, 17731.556711082325, 17912.552186194927, 18239.726404104214, 18520.16659250364, 17829.46511604654, 17841.910790445963, 17952.820471795116, 18017.459476215736, 18123.45629631113, 10744.677659670222, 11380.0, 11762.70593235176, 11789.292642441482, 11794.0, 12207.816882746589, 13249.0, 13589.116707413987, 13599.52401665937, 13676.658083547592, 11125.388103654377, 11261.324320540794, 11287.661370158909, 11405.0, 11511.30932144074, 10768.892311076788, 10790.082842958123, 10940.45297735126, 10999.835002474809, 11150.498227579616, 9302.02328755471, 9477.715668529956, 9539.379940303857, 9542.141207291377, 9559.0, 7405.296496832792, 7416.147143078615, 7420.406367490696, 7471.925280747123, 7475.327220550176, 7208.495405321577, 7213.0, 7244.166920803964, 7258.745943891939, 7327.890081648886], "value": 6597.565298361164, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [485.32425847867285, 493.33417332603204, 514.061837147206, 520.240448944541, 522.2114606943514, 6166.964901582478, 6171.691415429222, 6193.0, 6197.985654662236, 6246.250449946007, 5909.379515150912, 6094.9085763713465, 6138.938610613893, 6176.567640265183, 6189.81430557084, 4770.403699537558, 4885.682430641997, 4923.655344125913, 4944.085344211315, 4976.7013979161275, 4922.926156107661, 4954.7522623868845, 4968.652194346397, 4979.850604481871, 5004.299398084253, 4728.314394412808, 4879.730580511066, 4880.389951256093, 4885.853424397274, 4890.266460031006, 4878.975225281504, 4884.560389564938, 4887.995921672115, 4895.31465594817, 4900.117978763821, 4353.695241333108, 4363.585459381354, 4373.343998400233, 4388.5830846069575, 4410.140022695576, 6050.122732203849, 6072.392760723937, 6075.574709770318, 6088.634681919055, 6093.542984276197, 5618.63478873876, 5626.9437305626825, 5640.5463623323985, 5669.829905102853, 5675.5459563234845, 6428.742850285983, 6488.28628850826, 6499.740010399579, 6500.674966251716, 6537.248216455121, 4722.197226471508, 4750.311204875274, 4752.9762351188365, 4768.332433459318, 4776.99366266169, 6739.44992651688, 6804.829879253059, 6836.077129587541, 6888.896666550006, 6899.896501552481, 4830.372051633335, 4831.178699621073, 4872.171730805771, 4888.706677599355, 4904.60763138948, 4929.852104436873, 4937.136001199848, 4941.184704523714, 4967.726775027336, 4972.7513624318635, 5572.247746554244, 5582.553395728413, 5592.552595792326, 5623.746931388097, 5638.23883775685, 6445.45213656835, 6481.222253329631, 6486.643234622141, 6552.6068435894, 6572.0, 1619.8218195998431, 1693.889897156681, 1722.7157519009475, 1734.514335985926, 1738.19174084049, 1132.2413982631656, 1158.478684591937, 1163.3717792392183, 1171.4377098992532, 1194.4505527457468, 5592.692401917933, 5635.126555383922, 5687.146927960756, 5706.0, 5766.221560089418, 6453.741850325934, 6528.967355163194, 6628.304028077079, 6662.600243985376, 6684.398404143651, 5665.461781130786, 5710.632005914427, 5719.799807006818, 5761.683107429048, 5786.842631473722, 3499.5975462822103, 3501.8949431517094, 3510.157562185057, 3560.181158333598, 3583.283343331344, 6415.807525774234, 6428.5178611604315, 6438.967805160944, 6486.54594178403, 6510.777165303205, 6844.657767111625, 6861.451083913275, 6871.209810871814, 6871.862562748817, 6907.481938854606, 6389.297177310492, 6409.839754006187, 6452.161219041494, 6467.967660161763, 6484.351564843478, 6579.078928950046, 6595.571287866273, 6708.563943343667, 6711.389281557759, 6737.797866064026, 6579.736810527525, 6627.569208001464, 6639.136912201383, 6693.0, 6693.598384096969, 6464.545477267765, 6468.256150542559, 6539.901901471387, 6549.214094308713, 6553.803385898431, 4809.567138957511, 4824.131656301899, 4830.927536086891, 4832.734199619054, 4877.292792545132, 6628.520026985871, 6635.867282654416, 6647.434968027772, 6681.398674119353, 6728.798136055926, 6413.198350206224, 6422.807315780534, 6470.22357317125, 6504.837379065561, 6525.8694826104165, 6246.843828904313, 6396.520260980538, 6422.935770642421, 6484.1246431731115, 6573.309802470863, 6073.696315184311, 6252.968735156295, 6302.432781049728, 6363.3000369958345, 6380.266269379081, 6501.577397469149, 6529.085927969993, 6534.313897040743, 6536.542442029012, 6541.836454088686, 6361.522885783492, 6364.77723279683, 6373.788980093767, 6375.808725738235, 6385.744570217139], "value": 4987.836138992185, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [906.9954650226748, 909.1999040844058, 932.454514109246, 936.3726303376741, 942.0438255171, 18708.90645546772, 19601.60796784067, 19657.410277691655, 19671.704924426143, 21333.25333613324, 21073.367798966057, 21777.3466795996, 21893.562128757454, 21922.561548769016, 21938.122475100976, 1036.1658864613983, 1046.016744260393, 1048.8374301983206, 1048.973775655611, 1049.5539395756812, 1022.6625213679505, 1047.7590154264524, 1055.5144633468615, 1055.9683209503728, 1059.9364038157696, 1095.6822521468764, 1100.1199040767394, 1100.1748688483638, 1100.818364969779, 1100.9229353945186, 1091.5961094395125, 1094.0099210214769, 1098.3958822647512, 1098.8351747237898, 1101.6309536305348, 797.2824457987799, 799.564237490569, 800.4076983032572, 801.8075661841116, 801.8276070644839, 19535.902320488447, 19790.70313945292, 19800.80199198004, 19829.0, 20363.08366123528, 20692.79307206924, 21926.342209733735, 21962.0, 22424.66363004556, 22428.0, 16098.436554720529, 16379.836201638069, 16428.917855410768, 16435.506934791974, 16596.58508537284, 3630.0, 3667.5965643779164, 3668.413053911361, 3670.3209906167444, 3670.743047986642, 124970.0, 126747.36626316901, 127703.36148319292, 128182.35908820489, 129943.0, 17190.74213886793, 17317.307307707804, 17506.64986700259, 17530.824691753176, 17549.824501755076, 30204.84897575498, 30291.091267262018, 30810.845945770576, 31893.681063189535, 32105.51841722376, 7630.504017238862, 7931.881021784678, 8062.798430039296, 8322.417430779788, 8606.655733770704, 4183.45615070045, 4303.397524346594, 4310.31035034393, 4313.223619748413, 4316.525182229952, 5257.737113144328, 5302.867428314323, 5341.759620816995, 5362.731863406814, 5382.4886635769535, 1052.9578816847393, 1060.5545670818274, 1063.5586231713753, 1074.4788777442934, 1075.3279200499687, 672.143017652497, 683.6923384477005, 691.350130876974, 699.3845416033857, 704.3519961635308, 11552.995322694289, 11662.475188616532, 12229.205101668364, 12390.194637348544, 12839.358032098544, 698.0715648187897, 699.5697645947705, 704.1972151747012, 712.4051417066808, 713.5290708132711, 5541.695206763667, 5748.109043098243, 5795.0, 5845.883082338414, 5908.881822363529, 110629.0, 112734.0, 114127.42936285431, 114563.0, 115652.42173789078, 97318.5134074325, 97618.0, 99696.50455243177, 100170.0, 100783.49608251912, 86930.0, 87646.99999999872, 88359.55820220857, 91384.08615913888, 91798.08201917895, 10537.367757934548, 12036.939815300868, 12531.0, 12650.493980240688, 12665.303408312631, 38035.239295214495, 38611.806940965114, 39234.803825980685, 40307.79846100751, 41822.790886045375, 71096.28903710896, 71573.0, 72244.27755722376, 72805.63597181981, 73678.0, 16137.75793363124, 18472.690913635797, 18505.074746262362, 18507.35224267144, 18552.907235463736, 18729.344472943383, 20362.694559581916, 21138.365849024554, 21200.893995529925, 21624.0, 17710.380136695152, 18275.634487309915, 18348.17433215535, 18449.723254151468, 18499.445016649523, 15875.444359447363, 17651.911740441217, 17824.643507130044, 17829.910850445664, 18043.278268869097, 17977.10114494296, 18009.909950450165, 18246.72629910526, 18361.54096147607, 18791.34230301933, 13428.664283392993, 13518.93240533791, 13611.387487562886, 13923.164610123426, 13926.025178237425, 13978.580642580739, 14070.085444446078, 14157.575272741835, 14204.431822726976, 15071.020383675026], "value": 9307.580929370593, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1546.1999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 108.0, 101.0, 112.0, 109.0, 109.0, 110.0, 100.0, 110.0, 111.0], "value": 107.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 117.0, 140.0, 175.0, 116.0, 153.0, 141.0, 118.0, 175.0, 127.0], "value": 141.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 162.0, 163.0, 170.0, 172.0, 162.0, 165.0, 167.0, 168.0, 165.0], "value": 165.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 76.0, 168.0, 77.0, 76.0, 76.0, 75.0, 153.0, 79.0], "value": 93.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 78.0, 137.0, 79.0, 79.0, 79.0, 135.0, 79.0, 79.0, 128.0], "value": 95.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 115.0, 115.0, 114.0, 121.0, 114.0, 120.0, 123.0, 115.0], "value": 116.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 150.0, 151.0, 150.0, 152.0, 150.0, 154.0, 152.0, 151.0, 153.0], "value": 151.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 99.0, 96.0, 95.0, 94.0, 96.0, 94.0, 94.0, 94.0, 93.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 53.0, 53.0, 54.0, 53.0, 54.0, 59.0, 67.0, 54.0], "value": 55.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 56.0, 55.0, 63.0, 55.0, 55.0, 57.0, 61.0, 61.0], "value": 58.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 89.0, 78.0, 80.0, 85.0, 78.0, 79.0, 86.0, 90.0, 84.0], "value": 83.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 130.0, 131.0, 131.0, 128.0, 125.0, 124.0, 126.0, 122.0, 171.0], "value": 132.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 180.0, 178.0, 184.0, 182.0, 180.0, 174.0, 188.0, 181.0, 174.0], "value": 180.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 73.0, 68.0, 68.0, 72.0, 67.0, 68.0, 72.0, 73.0, 69.0], "value": 69.7, "unit": "score"}]}]} 15:36:04 INFO - Return code: 0 15:36:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:36:04 INFO - # TBPL SUCCESS # 15:36:04 INFO - Running post-action listener: _package_coverage_data 15:36:04 INFO - Running post-action listener: _resource_record_post_action 15:36:04 INFO - [mozharness: 2017-08-15 22:36:04.928840Z] Finished run-tests step (success) 15:36:04 INFO - Running post-run listener: _resource_record_post_run 15:36:05 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 4096; Write bytes: 438091776; Read time: 8; Write time: 422168 15:36:05 INFO - TinderboxPrint: CPU usage
13.2% 15:36:05 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 15:36:05 INFO - TinderboxPrint: I/O write bytes / time
438,091,776 / 422,168 15:36:05 INFO - TinderboxPrint: CPU idle
8,258.9 (86.8%) 15:36:05 INFO - TinderboxPrint: CPU user
1,201.3 (12.6%) 15:36:05 INFO - TinderboxPrint: Swap in / out
0 / 0 15:36:05 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 113754112; Read time: 0; Write time: 108940 15:36:05 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 15:36:05 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 0; Write bytes: 324337664; Read time: 0; Write time: 313228 15:36:05 INFO - Running post-run listener: _upload_blobber_files 15:36:05 INFO - Blob upload gear active. 15:36:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:36:05 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 15:36:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:36:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:36:06 INFO - (blobuploader) - INFO - Open directory for files ... 15:36:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:36:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:36:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:36:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:36:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:36:07 INFO - (blobuploader) - INFO - Done attempting. 15:36:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:36:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:36:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:36:14 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:36:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:36:14 INFO - (blobuploader) - INFO - Done attempting. 15:36:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:36:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:36:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:36:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:36:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:36:15 INFO - (blobuploader) - INFO - Done attempting. 15:36:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:36:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:36:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:36:16 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:36:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:36:16 INFO - (blobuploader) - INFO - Done attempting. 15:36:16 INFO - (blobuploader) - INFO - Iteration through files over. 15:36:16 INFO - Return code: 0 15:36:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:36:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:36:16 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0f1db7137659d4029c635c1db64535bf25724f2028bc920e8c6a245776ef635a7113f9192b87df280503bf436ccc5650fef63a2962c70ed884232789da614209", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4350b999f7edde83c85521fe25471134719adcf560eb9848d186277eba9920ac98831c3141022ed2507e713f059d33c4332220ca9f4d6fbbe1bcfc8090720c85", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1ed9d9d6ebd05bf39b3004cd4970ae3261a7c2959b61a8488f88f7c31609670bc33703a66b1f3bb6851e1c9f0ce43da603867e144bbef28641443c82dd742e7a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a7ca15c0cd94dd3af593372b5d27f2d919c00436e242b8c2050ccff9c0579a7c159644149f97506d082fbad5db01c318364d04da91e740965967a3ff5c6c85a4"} 15:36:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:36:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:36:16 INFO - Contents: 15:36:16 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0f1db7137659d4029c635c1db64535bf25724f2028bc920e8c6a245776ef635a7113f9192b87df280503bf436ccc5650fef63a2962c70ed884232789da614209", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4350b999f7edde83c85521fe25471134719adcf560eb9848d186277eba9920ac98831c3141022ed2507e713f059d33c4332220ca9f4d6fbbe1bcfc8090720c85", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1ed9d9d6ebd05bf39b3004cd4970ae3261a7c2959b61a8488f88f7c31609670bc33703a66b1f3bb6851e1c9f0ce43da603867e144bbef28641443c82dd742e7a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a7ca15c0cd94dd3af593372b5d27f2d919c00436e242b8c2050ccff9c0579a7c159644149f97506d082fbad5db01c318364d04da91e740965967a3ff5c6c85a4"} 15:36:16 INFO - Running post-run listener: copy_logs_to_upload_dir 15:36:16 INFO - Copying logs to upload dir... 15:36:16 INFO - mkdir: /builds/slave/test/build/upload/logs 15:36:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.438524 ========= master_lag: 1.42 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-08-15 15:36:17.914001) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-15 15:36:17.922177) ========= 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=88e9d754c8c9af9f7805d7f3000001bd-1502835077.167429-1301068326 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/sha512/0f1db7137659d4029c635c1db64535bf25724f2028bc920e8c6a245776ef635a7113f9192b87df280503bf436ccc5650fef63a2962c70ed884232789da614209", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4350b999f7edde83c85521fe25471134719adcf560eb9848d186277eba9920ac98831c3141022ed2507e713f059d33c4332220ca9f4d6fbbe1bcfc8090720c85", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1ed9d9d6ebd05bf39b3004cd4970ae3261a7c2959b61a8488f88f7c31609670bc33703a66b1f3bb6851e1c9f0ce43da603867e144bbef28641443c82dd742e7a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a7ca15c0cd94dd3af593372b5d27f2d919c00436e242b8c2050ccff9c0579a7c159644149f97506d082fbad5db01c318364d04da91e740965967a3ff5c6c85a4"} build_url:https://queue.taskcluster.net/v1/task/RMB3t9HoRkqz14OC2cjDLw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.010459 build_url: 'https://queue.taskcluster.net/v1/task/RMB3t9HoRkqz14OC2cjDLw/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0f1db7137659d4029c635c1db64535bf25724f2028bc920e8c6a245776ef635a7113f9192b87df280503bf436ccc5650fef63a2962c70ed884232789da614209", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4350b999f7edde83c85521fe25471134719adcf560eb9848d186277eba9920ac98831c3141022ed2507e713f059d33c4332220ca9f4d6fbbe1bcfc8090720c85", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1ed9d9d6ebd05bf39b3004cd4970ae3261a7c2959b61a8488f88f7c31609670bc33703a66b1f3bb6851e1c9f0ce43da603867e144bbef28641443c82dd742e7a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a7ca15c0cd94dd3af593372b5d27f2d919c00436e242b8c2050ccff9c0579a7c159644149f97506d082fbad5db01c318364d04da91e740965967a3ff5c6c85a4"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-15 15:36:17.947960) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-15 15:36:17.948273) ========= 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=88e9d754c8c9af9f7805d7f3000001bd-1502835077.167429-1301068326 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004503 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-15 15:36:18.001501) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-15 15:36:18.001816) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-15 15:36:18.002091) ========= ========= Total master_lag: 1.58 =========