builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-059 starttime: 1501550960.32 results: success (0) revision: 44121dbcac6a9d3ff18ed087a09b3205e5a04db1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-07-31 18:29:20.319694) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-07-31 18:29:20.320114) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-31 18:29:20.320389) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-31 18:29:20.395361) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-31 18:29:20.395611) ========= 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=3a3620fb28aea1604ab609ef000001d2-1501550826.510254-1621757349 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-07-31 18:29:20-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 229M=0s 2017-07-31 18:29:20 (229 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.243707 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-31 18:29:20.671897) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-31 18:29:20.672191) ========= 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=3a3620fb28aea1604ab609ef000001d2-1501550826.510254-1621757349 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015114 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-31 18:29:20.722312) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-31 18:29:20.722599) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 44121dbcac6a9d3ff18ed087a09b3205e5a04db1 --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 44121dbcac6a9d3ff18ed087a09b3205e5a04db1 --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=3a3620fb28aea1604ab609ef000001d2-1501550826.510254-1621757349 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-07-31 18:29:20,810 truncating revision to first 12 chars 2017-07-31 18:29:20,810 Setting DEBUG logging. 2017-07-31 18:29:20,810 attempt 1/10 2017-07-31 18:29:20,810 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/44121dbcac6a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-07-31 18:29:21,325 unpacking tar archive at: mozilla-central-44121dbcac6a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.942266 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-31 18:29:21.676470) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-31 18:29:21.676747) ========= script_repo_revision: 44121dbcac6a9d3ff18ed087a09b3205e5a04db1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-31 18:29:21.677139) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-31 18:29:21.677432) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-31 18:29:21.690084) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2017-07-31 18:29:21.690344) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=3a3620fb28aea1604ab609ef000001d2-1501550826.510254-1621757349 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:29:21 INFO - MultiFileLogger online at 20170731 18:29:21 in /builds/slave/test 18:29:21 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 18:29:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:29:21 INFO - {'append_to_log': False, 18:29:21 INFO - 'base_work_dir': '/builds/slave/test', 18:29:21 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 18:29:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:29:21 INFO - 'branch': 'Firefox-Non-PGO', 18:29:21 INFO - 'buildbot_json_path': 'buildprops.json', 18:29:21 INFO - 'code_coverage': False, 18:29:21 INFO - 'config_files': ('talos/linux_config.py',), 18:29:21 INFO - 'default_actions': ('clobber', 18:29:21 INFO - 'read-buildbot-config', 18:29:21 INFO - 'download-and-extract', 18:29:21 INFO - 'populate-webroot', 18:29:21 INFO - 'create-virtualenv', 18:29:21 INFO - 'install', 18:29:21 INFO - 'setup-mitmproxy', 18:29:21 INFO - 'run-tests'), 18:29:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:29:21 INFO - 'disable_ccov_upload': False, 18:29:21 INFO - 'download_minidump_stackwalk': True, 18:29:21 INFO - 'download_symbols': 'ondemand', 18:29:21 INFO - 'enable_stylo': False, 18:29:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:29:21 INFO - 'tooltool.py': '/tools/tooltool.py', 18:29:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:29:21 INFO - '/tools/misc-python/virtualenv.py')}, 18:29:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:29:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:29:21 INFO - 'gecko_profile': False, 18:29:21 INFO - 'gecko_profile_interval': 0, 18:29:21 INFO - 'installer_path': 'installer.exe', 18:29:21 INFO - 'log_level': 'info', 18:29:21 INFO - 'log_name': 'talos', 18:29:21 INFO - 'log_to_console': True, 18:29:21 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:29:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:29:21 INFO - 'opt_config_files': (), 18:29:21 INFO - 'pip_index': False, 18:29:21 INFO - 'suite': 'dromaeojs-e10s', 18:29:21 INFO - 'system_bits': '32', 18:29:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:29:21 INFO - 'title': 'talos-linux64-ix-059', 18:29:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:29:21 INFO - 'use_talos_json': True, 18:29:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:29:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:29:21 INFO - 'work_dir': 'build'} 18:29:21 INFO - [mozharness: 2017-08-01 01:29:21.992311Z] Running clobber step. 18:29:21 INFO - Running pre-action listener: _resource_record_pre_action 18:29:21 INFO - Running main action method: clobber 18:29:21 INFO - rmtree: /builds/slave/test/build 18:29:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:29:22 INFO - Running post-action listener: _resource_record_post_action 18:29:22 INFO - [mozharness: 2017-08-01 01:29:22.984345Z] Finished clobber step (success) 18:29:22 INFO - [mozharness: 2017-08-01 01:29:22.984447Z] Running read-buildbot-config step. 18:29:22 INFO - Running pre-action listener: _resource_record_pre_action 18:29:22 INFO - Running main action method: read_buildbot_config 18:29:22 INFO - Using buildbot properties: 18:29:22 INFO - { 18:29:22 INFO - "project": "", 18:29:22 INFO - "product": "firefox", 18:29:22 INFO - "who": "kwierso@gmail.com", 18:29:22 INFO - "installer_path": "public/build/target.tar.bz2", 18:29:22 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 18:29:22 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 18:29:22 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 18:29:22 INFO - "stage_platform": "linux64", 18:29:22 INFO - "basedir": "/builds/slave/test", 18:29:22 INFO - "buildnumber": 1050, 18:29:22 INFO - "platform": "ubuntu64_hw", 18:29:22 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 18:29:22 INFO - "slavebuilddir": "test", 18:29:22 INFO - "taskId": "BFwx8JdRQuOr6SDDODNqUg", 18:29:22 INFO - "branch": "mozilla-central", 18:29:22 INFO - "script_repo_revision": "production", 18:29:22 INFO - "revision": "44121dbcac6a9d3ff18ed087a09b3205e5a04db1", 18:29:22 INFO - "slavename": "talos-linux64-ix-059", 18:29:22 INFO - "repo_path": "mozilla-central" 18:29:22 INFO - } 18:29:22 INFO - Finding installer, test and symbols from parent task. 18:29:22 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BFwx8JdRQuOr6SDDODNqUg'}, attempt #1 18:29:23 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BFwx8JdRQuOr6SDDODNqUg'}, attempt #1 18:29:23 INFO - Task dependencies: ZbeB3PV6RPy_XbcR57i83g 18:29:23 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZbeB3PV6RPy_XbcR57i83g'}, attempt #1 18:29:24 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/ZbeB3PV6RPy_XbcR57i83g/artifacts/public/build/target.tar.bz2 18:29:24 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/ZbeB3PV6RPy_XbcR57i83g/artifacts/public/build/target.test_packages.json 18:29:24 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/ZbeB3PV6RPy_XbcR57i83g/artifacts/public/build/target.crashreporter-symbols.zip 18:29:24 INFO - Running post-action listener: _resource_record_post_action 18:29:24 INFO - [mozharness: 2017-08-01 01:29:24.391314Z] Finished read-buildbot-config step (success) 18:29:24 INFO - [mozharness: 2017-08-01 01:29:24.391535Z] Running download-and-extract step. 18:29:24 INFO - Running pre-action listener: _resource_record_pre_action 18:29:24 INFO - Running main action method: download_and_extract 18:29:24 INFO - mkdir: /builds/slave/test/build/tests 18:29:24 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')]} 18:29:24 INFO - trying https://queue.taskcluster.net/v1/task/ZbeB3PV6RPy_XbcR57i83g/artifacts/public/build/target.test_packages.json 18:29:24 INFO - Downloading https://queue.taskcluster.net/v1/task/ZbeB3PV6RPy_XbcR57i83g/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 18:29:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZbeB3PV6RPy_XbcR57i83g/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 18:29:24 INFO - Downloaded 989 bytes. 18:29:24 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 18:29:24 INFO - Using the following test package requirements: 18:29:24 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 18:29:24 INFO - u'common': [u'target.common.tests.zip'], 18:29:24 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 18:29:24 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 18:29:24 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 18:29:24 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 18:29:24 INFO - u'mozbase': [u'target.common.tests.zip'], 18:29:24 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 18:29:24 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 18:29:24 INFO - u'web-platform': [u'target.common.tests.zip', 18:29:24 INFO - u'target.web-platform.tests.tar.gz'], 18:29:24 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 18:29:24 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 18:29:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZbeB3PV6RPy_XbcR57i83g/artifacts/public/build/target.common.tests.zip 18:29:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZbeB3PV6RPy_XbcR57i83g/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:29:24 INFO - Fetch https://queue.taskcluster.net/v1/task/ZbeB3PV6RPy_XbcR57i83g/artifacts/public/build/target.common.tests.zip into memory 18:29:28 INFO - Content-Length response header: 72390366 18:29:28 INFO - Bytes received: 72390366 18:29:39 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 18:29:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZbeB3PV6RPy_XbcR57i83g/artifacts/public/build/target.common.tests.zip 18:29:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZbeB3PV6RPy_XbcR57i83g/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:29:39 INFO - Fetch https://queue.taskcluster.net/v1/task/ZbeB3PV6RPy_XbcR57i83g/artifacts/public/build/target.common.tests.zip into memory 18:29:42 INFO - Content-Length response header: 72390366 18:29:42 INFO - Bytes received: 72390366 18:29:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZbeB3PV6RPy_XbcR57i83g/artifacts/public/build/target.talos.tests.zip 18:29:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZbeB3PV6RPy_XbcR57i83g/artifacts/public/build/target.talos.tests.zip'}, attempt #1 18:29:54 INFO - Fetch https://queue.taskcluster.net/v1/task/ZbeB3PV6RPy_XbcR57i83g/artifacts/public/build/target.talos.tests.zip into memory 18:29:55 INFO - Content-Length response header: 14496467 18:29:55 INFO - Bytes received: 14496467 18:29:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:29:55 INFO - trying https://queue.taskcluster.net/v1/task/ZbeB3PV6RPy_XbcR57i83g/artifacts/public/build/target.tar.bz2 18:29:55 INFO - Downloading https://queue.taskcluster.net/v1/task/ZbeB3PV6RPy_XbcR57i83g/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 18:29:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZbeB3PV6RPy_XbcR57i83g/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 18:29:59 INFO - Downloaded 62400473 bytes. 18:29:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZbeB3PV6RPy_XbcR57i83g/artifacts/public/build/target.tar.bz2 18:29:59 INFO - mkdir: /builds/slave/test/properties 18:29:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:29:59 INFO - Writing to file /builds/slave/test/properties/build_url 18:29:59 INFO - Contents: 18:29:59 INFO - build_url:https://queue.taskcluster.net/v1/task/ZbeB3PV6RPy_XbcR57i83g/artifacts/public/build/target.tar.bz2 18:29:59 INFO - Running post-action listener: _resource_record_post_action 18:29:59 INFO - Running post-action listener: set_extra_try_arguments 18:29:59 INFO - [mozharness: 2017-08-01 01:29:59.009063Z] Finished download-and-extract step (success) 18:29:59 INFO - [mozharness: 2017-08-01 01:29:59.009319Z] Running populate-webroot step. 18:29:59 INFO - Running pre-action listener: _resource_record_pre_action 18:29:59 INFO - Running main action method: populate_webroot 18:29:59 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:29:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:29:59 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:29:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:29:59 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:29:59 INFO - 'tests': ['damp', 'tps']}, 18:29:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:29:59 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:29:59 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:29:59 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:29:59 INFO - 'other-e10s': {'tests': ['a11yr', 18:29:59 INFO - 'ts_paint', 18:29:59 INFO - 'tpaint', 18:29:59 INFO - 'sessionrestore', 18:29:59 INFO - 'sessionrestore_many_windows', 18:29:59 INFO - 'sessionrestore_no_auto_restore', 18:29:59 INFO - 'tabpaint']}, 18:29:59 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 18:29:59 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 18:29:59 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:29:59 INFO - 'talos_options': ['--mitmproxy', 18:29:59 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:29:59 INFO - '--firstNonBlankPaint'], 18:29:59 INFO - 'tests': ['quantum_pageload_google', 18:29:59 INFO - 'quantum_pageload_youtube', 18:29:59 INFO - 'quantum_pageload_amazon', 18:29:59 INFO - 'quantum_pageload_facebook']}, 18:29:59 INFO - 'quantum-pageload-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:29:59 INFO - 'talos_options': ['--stylo', 18:29:59 INFO - '--mitmproxy', 18:29:59 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:29:59 INFO - '--firstNonBlankPaint'], 18:29:59 INFO - 'tests': ['quantum_pageload_google', 18:29:59 INFO - 'quantum_pageload_youtube', 18:29:59 INFO - 'quantum_pageload_amazon', 18:29:59 INFO - 'quantum_pageload_facebook']}, 18:29:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:29:59 INFO - 'tsvgr_opacity', 18:29:59 INFO - 'tart', 18:29:59 INFO - 'tscrollx', 18:29:59 INFO - 'cart', 18:29:59 INFO - 'tsvg_static']}, 18:29:59 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:29:59 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:29:59 INFO - 'talos_options': ['--stylo', 18:29:59 INFO - '--stylo-threads=1', 18:29:59 INFO - '--mitmproxy', 18:29:59 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:29:59 INFO - '--firstNonBlankPaint'], 18:29:59 INFO - 'tests': ['tp6_google', 18:29:59 INFO - 'tp6_youtube', 18:29:59 INFO - 'tp6_amazon', 18:29:59 INFO - 'tp6_facebook']}, 18:29:59 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:29:59 INFO - 'talos_options': ['--xperf_path', 18:29:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:29:59 INFO - 'tests': ['tp5n']}}} 18:29:59 INFO - Running post-action listener: _resource_record_post_action 18:29:59 INFO - [mozharness: 2017-08-01 01:29:59.025368Z] Finished populate-webroot step (success) 18:29:59 INFO - [mozharness: 2017-08-01 01:29:59.025458Z] Running create-virtualenv step. 18:29:59 INFO - Running pre-action listener: _resource_record_pre_action 18:29:59 INFO - Running main action method: create_virtualenv 18:29:59 INFO - Creating virtualenv /builds/slave/test/build/venv 18:29:59 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 18:29:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:29:59 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 18:29:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:29:59 INFO - Using real prefix '/usr' 18:29:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:29:59 INFO - Installing distribute.............................................................................................................................................................................................done. 18:30:01 INFO - Installing pip.................done. 18:30:01 INFO - Return code: 0 18:30:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:30:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:30:02 INFO - Reading from file tmpfile_stdout 18:30:02 INFO - Output received: 18:30:02 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) 18:30:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:30: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')]} 18:30:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30: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')]} 18:30:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x222d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23faa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x259dd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x259aeb0>, '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 0x259bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x259c240>, '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': '3a3620fb28aea1604ab609ef000001d2-1501550826.510254-1621757349', '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 18:30:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:30:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:30:02 INFO - Using env: {'DISPLAY': ':0', 18:30:02 INFO - 'HOME': '/home/cltbld', 18:30:02 INFO - 'LANG': 'en_US.UTF-8', 18:30:02 INFO - 'LANGUAGE': 'en_US:en', 18:30:02 INFO - 'LOGNAME': 'cltbld', 18:30:02 INFO - 'MAIL': '/var/mail/cltbld', 18:30:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:02 INFO - 'MOZ_NO_REMOTE': '1', 18:30:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:30:02 INFO - 'NO_EM_RESTART': '1', 18:30:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:30:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:02 INFO - 'PWD': '/builds/slave/test', 18:30:02 INFO - 'SHELL': '/bin/bash', 18:30:02 INFO - 'SHLVL': '1', 18:30:02 INFO - 'TERM': 'linux', 18:30:02 INFO - 'TMOUT': '86400', 18:30:02 INFO - 'USER': 'cltbld', 18:30:02 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1501550826.510254-1621757349', 18:30:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:02 INFO - '_': '/tools/buildbot/bin/python'} 18:30:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:02 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 18:30:02 INFO - Cleaning up... 18:30:02 INFO - Return code: 0 18:30:02 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:30: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')]} 18:30:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30: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')]} 18:30:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x222d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23faa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x259dd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x259aeb0>, '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 0x259bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x259c240>, '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': '3a3620fb28aea1604ab609ef000001d2-1501550826.510254-1621757349', '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 18:30:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:30:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:30:02 INFO - Using env: {'DISPLAY': ':0', 18:30:02 INFO - 'HOME': '/home/cltbld', 18:30:02 INFO - 'LANG': 'en_US.UTF-8', 18:30:02 INFO - 'LANGUAGE': 'en_US:en', 18:30:02 INFO - 'LOGNAME': 'cltbld', 18:30:02 INFO - 'MAIL': '/var/mail/cltbld', 18:30:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:02 INFO - 'MOZ_NO_REMOTE': '1', 18:30:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:30:02 INFO - 'NO_EM_RESTART': '1', 18:30:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:30:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:02 INFO - 'PWD': '/builds/slave/test', 18:30:02 INFO - 'SHELL': '/bin/bash', 18:30:02 INFO - 'SHLVL': '1', 18:30:02 INFO - 'TERM': 'linux', 18:30:02 INFO - 'TMOUT': '86400', 18:30:02 INFO - 'USER': 'cltbld', 18:30:02 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1501550826.510254-1621757349', 18:30:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:02 INFO - '_': '/tools/buildbot/bin/python'} 18:30:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:02 INFO - Downloading/unpacking psutil>=3.1.1 18:30:02 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 18:30:02 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 18:30:02 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 18:30:02 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 18:30:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:30:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:30:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:30:03 INFO - Installing collected packages: psutil 18:30:03 INFO - Running setup.py install for psutil 18:30:03 INFO - building 'psutil._psutil_linux' extension 18:30:03 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 18:30:04 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 18:30:04 INFO - building 'psutil._psutil_posix' extension 18:30:04 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 18:30:04 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 18:30:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:30:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:30:05 INFO - Successfully installed psutil 18:30:05 INFO - Cleaning up... 18:30:05 INFO - Return code: 0 18:30:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:30: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')]} 18:30:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30: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')]} 18:30:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x222d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23faa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x259dd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x259aeb0>, '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 0x259bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x259c240>, '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': '3a3620fb28aea1604ab609ef000001d2-1501550826.510254-1621757349', '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 18:30:05 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 18:30:05 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 18:30:05 INFO - Using env: {'DISPLAY': ':0', 18:30:05 INFO - 'HOME': '/home/cltbld', 18:30:05 INFO - 'LANG': 'en_US.UTF-8', 18:30:05 INFO - 'LANGUAGE': 'en_US:en', 18:30:05 INFO - 'LOGNAME': 'cltbld', 18:30:05 INFO - 'MAIL': '/var/mail/cltbld', 18:30:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:05 INFO - 'MOZ_NO_REMOTE': '1', 18:30:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:30:05 INFO - 'NO_EM_RESTART': '1', 18:30:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:30:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:05 INFO - 'PWD': '/builds/slave/test', 18:30:05 INFO - 'SHELL': '/bin/bash', 18:30:05 INFO - 'SHLVL': '1', 18:30:05 INFO - 'TERM': 'linux', 18:30:05 INFO - 'TMOUT': '86400', 18:30:05 INFO - 'USER': 'cltbld', 18:30:05 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1501550826.510254-1621757349', 18:30:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:05 INFO - '_': '/tools/buildbot/bin/python'} 18:30:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:30:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:06 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:30:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:30:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:30:06 INFO - Installing collected packages: mozsystemmonitor 18:30:06 INFO - Running setup.py install for mozsystemmonitor 18:30:06 INFO - Successfully installed mozsystemmonitor 18:30:06 INFO - Cleaning up... 18:30:06 INFO - Return code: 0 18:30:06 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:30:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:30:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:30:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x222d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23faa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x259dd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x259aeb0>, '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 0x259bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x259c240>, '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': '3a3620fb28aea1604ab609ef000001d2-1501550826.510254-1621757349', '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 18:30:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:30:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:30:06 INFO - Using env: {'DISPLAY': ':0', 18:30:06 INFO - 'HOME': '/home/cltbld', 18:30:06 INFO - 'LANG': 'en_US.UTF-8', 18:30:06 INFO - 'LANGUAGE': 'en_US:en', 18:30:06 INFO - 'LOGNAME': 'cltbld', 18:30:06 INFO - 'MAIL': '/var/mail/cltbld', 18:30:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:06 INFO - 'MOZ_NO_REMOTE': '1', 18:30:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:30:06 INFO - 'NO_EM_RESTART': '1', 18:30:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:30:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:06 INFO - 'PWD': '/builds/slave/test', 18:30:06 INFO - 'SHELL': '/bin/bash', 18:30:06 INFO - 'SHLVL': '1', 18:30:06 INFO - 'TERM': 'linux', 18:30:06 INFO - 'TMOUT': '86400', 18:30:06 INFO - 'USER': 'cltbld', 18:30:06 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1501550826.510254-1621757349', 18:30:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:06 INFO - '_': '/tools/buildbot/bin/python'} 18:30:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:06 INFO - Downloading/unpacking jsonschema==2.5.1 18:30:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:30:07 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:30: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 18:30: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 18:30: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 18:30: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 18:30:08 INFO - Downloading functools32-3.2.3-2.tar.gz 18:30:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:30:08 INFO - warning: no files found matching '*.txt' 18:30:08 INFO - no previously-included directories found matching 'build' 18:30:08 INFO - no previously-included directories found matching 'dist' 18:30:08 INFO - no previously-included directories found matching '.git*' 18:30:08 INFO - Installing collected packages: jsonschema, functools32 18:30:08 INFO - Running setup.py install for functools32 18:30:08 INFO - warning: no files found matching '*.txt' 18:30:08 INFO - no previously-included directories found matching 'build' 18:30:08 INFO - no previously-included directories found matching 'dist' 18:30:08 INFO - no previously-included directories found matching '.git*' 18:30:08 INFO - Successfully installed jsonschema functools32 18:30:08 INFO - Cleaning up... 18:30:08 INFO - Return code: 0 18:30:08 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:30: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')]} 18:30:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30: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')]} 18:30:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x222d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23faa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x259dd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x259aeb0>, '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 0x259bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x259c240>, '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': '3a3620fb28aea1604ab609ef000001d2-1501550826.510254-1621757349', '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 18:30:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:30:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:30:08 INFO - Using env: {'DISPLAY': ':0', 18:30:08 INFO - 'HOME': '/home/cltbld', 18:30:08 INFO - 'LANG': 'en_US.UTF-8', 18:30:08 INFO - 'LANGUAGE': 'en_US:en', 18:30:08 INFO - 'LOGNAME': 'cltbld', 18:30:08 INFO - 'MAIL': '/var/mail/cltbld', 18:30:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:08 INFO - 'MOZ_NO_REMOTE': '1', 18:30:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:30:08 INFO - 'NO_EM_RESTART': '1', 18:30:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:30:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:08 INFO - 'PWD': '/builds/slave/test', 18:30:08 INFO - 'SHELL': '/bin/bash', 18:30:08 INFO - 'SHLVL': '1', 18:30:08 INFO - 'TERM': 'linux', 18:30:08 INFO - 'TMOUT': '86400', 18:30:08 INFO - 'USER': 'cltbld', 18:30:08 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1501550826.510254-1621757349', 18:30:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:08 INFO - '_': '/tools/buildbot/bin/python'} 18:30:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:08 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:30:08 INFO - Cleaning up... 18:30:08 INFO - Return code: 0 18:30:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:30: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')]} 18:30:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30: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')]} 18:30:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x222d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23faa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x259dd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x259aeb0>, '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 0x259bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x259c240>, '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': '3a3620fb28aea1604ab609ef000001d2-1501550826.510254-1621757349', '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 18:30:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:30:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:30:08 INFO - Using env: {'DISPLAY': ':0', 18:30:08 INFO - 'HOME': '/home/cltbld', 18:30:08 INFO - 'LANG': 'en_US.UTF-8', 18:30:08 INFO - 'LANGUAGE': 'en_US:en', 18:30:08 INFO - 'LOGNAME': 'cltbld', 18:30:08 INFO - 'MAIL': '/var/mail/cltbld', 18:30:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:08 INFO - 'MOZ_NO_REMOTE': '1', 18:30:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:30:08 INFO - 'NO_EM_RESTART': '1', 18:30:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:30:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:08 INFO - 'PWD': '/builds/slave/test', 18:30:08 INFO - 'SHELL': '/bin/bash', 18:30:08 INFO - 'SHLVL': '1', 18:30:08 INFO - 'TERM': 'linux', 18:30:08 INFO - 'TMOUT': '86400', 18:30:08 INFO - 'USER': 'cltbld', 18:30:08 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1501550826.510254-1621757349', 18:30:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:08 INFO - '_': '/tools/buildbot/bin/python'} 18:30:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:08 INFO - Downloading/unpacking blobuploader==1.2.4 18:30: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 18:30: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 18:30: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 18:30: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 18:30:09 INFO - Downloading blobuploader-1.2.4.tar.gz 18:30:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:30:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:30:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:30:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:30:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:10 INFO - Downloading docopt-0.6.1.tar.gz 18:30:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:30:10 INFO - Installing collected packages: blobuploader, requests, docopt 18:30:10 INFO - Running setup.py install for blobuploader 18:30:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:30:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:30:10 INFO - Running setup.py install for requests 18:30:10 INFO - Running setup.py install for docopt 18:30:10 INFO - Successfully installed blobuploader requests docopt 18:30:10 INFO - Cleaning up... 18:30:11 INFO - Return code: 0 18:30:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:30: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')]} 18:30:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30: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')]} 18:30:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:11 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 0x222d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23faa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x259dd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x259aeb0>, '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 0x259bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x259c240>, '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': '3a3620fb28aea1604ab609ef000001d2-1501550826.510254-1621757349', '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 18:30:11 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 18:30:11 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 18:30:11 INFO - Using env: {'DISPLAY': ':0', 18:30:11 INFO - 'HOME': '/home/cltbld', 18:30:11 INFO - 'LANG': 'en_US.UTF-8', 18:30:11 INFO - 'LANGUAGE': 'en_US:en', 18:30:11 INFO - 'LOGNAME': 'cltbld', 18:30:11 INFO - 'MAIL': '/var/mail/cltbld', 18:30:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:11 INFO - 'MOZ_NO_REMOTE': '1', 18:30:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:30:11 INFO - 'NO_EM_RESTART': '1', 18:30:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:30:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:11 INFO - 'PWD': '/builds/slave/test', 18:30:11 INFO - 'SHELL': '/bin/bash', 18:30:11 INFO - 'SHLVL': '1', 18:30:11 INFO - 'TERM': 'linux', 18:30:11 INFO - 'TMOUT': '86400', 18:30:11 INFO - 'USER': 'cltbld', 18:30:11 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1501550826.510254-1621757349', 18:30:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:11 INFO - '_': '/tools/buildbot/bin/python'} 18:30:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:30:11 INFO - Running setup.py (path:/tmp/pip-HpSs6H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:30:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:30:11 INFO - Running setup.py (path:/tmp/pip-C0wk6c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:30:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:30:11 INFO - Running setup.py (path:/tmp/pip-SOc88z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:30:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:30:11 INFO - Running setup.py (path:/tmp/pip-5pN480-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:30:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:30:11 INFO - Running setup.py (path:/tmp/pip-datXhD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:30:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:30:11 INFO - Running setup.py (path:/tmp/pip-JWsd88-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:30:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:30:11 INFO - Running setup.py (path:/tmp/pip-hx2fuB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:30:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:30:11 INFO - Running setup.py (path:/tmp/pip-6z7WH4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:30:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:30:11 INFO - Running setup.py (path:/tmp/pip-0O0RID-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:30:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:30:11 INFO - Running setup.py (path:/tmp/pip-bnFaII-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:30:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:30:11 INFO - Running setup.py (path:/tmp/pip-ql8NBc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:30:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:30:12 INFO - Running setup.py (path:/tmp/pip-ygl0NC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:30:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:30:12 INFO - Running setup.py (path:/tmp/pip-n2cViP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:30:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:30:12 INFO - Running setup.py (path:/tmp/pip-P_qMfE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:30:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:30:12 INFO - Running setup.py (path:/tmp/pip-2IvKx1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:30:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:30:12 INFO - Running setup.py (path:/tmp/pip-Yaq06g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:30:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:30:12 INFO - Running setup.py (path:/tmp/pip-6FjUrG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:30:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:30:12 INFO - Running setup.py install for manifestparser 18:30:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:30:12 INFO - Running setup.py install for mozcrash 18:30:12 INFO - Running setup.py install for mozdebug 18:30:12 INFO - Running setup.py install for mozdevice 18:30:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:30:12 INFO - Running setup.py install for mozfile 18:30:12 INFO - Running setup.py install for mozhttpd 18:30:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:30:13 INFO - Running setup.py install for mozinfo 18:30:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:30:13 INFO - Running setup.py install for mozInstall 18:30:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:30:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:30:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:30:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:30:13 INFO - Running setup.py install for mozleak 18:30:13 INFO - Running setup.py install for mozlog 18:30:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:30:13 INFO - Running setup.py install for moznetwork 18:30:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:30:13 INFO - Running setup.py install for mozprocess 18:30:13 INFO - Running setup.py install for mozprofile 18:30:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:30:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:30:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:30:13 INFO - Running setup.py install for mozrunner 18:30:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:30:13 INFO - Running setup.py install for mozscreenshot 18:30:14 INFO - Running setup.py install for moztest 18:30:14 INFO - Running setup.py install for mozversion 18:30:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:30:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:30:14 INFO - Cleaning up... 18:30:14 INFO - Return code: 0 18:30:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:30:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:30:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:30:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:14 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 0x222d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23faa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x259dd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x259aeb0>, '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 0x259bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x259c240>, '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': '3a3620fb28aea1604ab609ef000001d2-1501550826.510254-1621757349', '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 18:30:14 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 18:30:14 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 18:30:14 INFO - Using env: {'DISPLAY': ':0', 18:30:14 INFO - 'HOME': '/home/cltbld', 18:30:14 INFO - 'LANG': 'en_US.UTF-8', 18:30:14 INFO - 'LANGUAGE': 'en_US:en', 18:30:14 INFO - 'LOGNAME': 'cltbld', 18:30:14 INFO - 'MAIL': '/var/mail/cltbld', 18:30:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:14 INFO - 'MOZ_NO_REMOTE': '1', 18:30:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:30:14 INFO - 'NO_EM_RESTART': '1', 18:30:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:30:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:14 INFO - 'PWD': '/builds/slave/test', 18:30:14 INFO - 'SHELL': '/bin/bash', 18:30:14 INFO - 'SHLVL': '1', 18:30:14 INFO - 'TERM': 'linux', 18:30:14 INFO - 'TMOUT': '86400', 18:30:14 INFO - 'USER': 'cltbld', 18:30:14 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1501550826.510254-1621757349', 18:30:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:14 INFO - '_': '/tools/buildbot/bin/python'} 18:30:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:30:14 INFO - Running setup.py (path:/tmp/pip-gZqZfk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:30:14 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)) 18:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:30:14 INFO - Running setup.py (path:/tmp/pip-hbIwoM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:30:14 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)) 18:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:30:14 INFO - Running setup.py (path:/tmp/pip-Z2u8v6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:30:14 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)) 18:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:30:14 INFO - Running setup.py (path:/tmp/pip-B3CD4E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:30:14 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)) 18:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:30:14 INFO - Running setup.py (path:/tmp/pip-i7kBEA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:30:14 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)) 18:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:30:14 INFO - Running setup.py (path:/tmp/pip-7FwAka-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:30:14 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)) 18:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:30:14 INFO - Running setup.py (path:/tmp/pip-S9eLM_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:30:14 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)) 18:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:30:14 INFO - Running setup.py (path:/tmp/pip-Rz6kvj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:30:15 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)) 18:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:30:15 INFO - Running setup.py (path:/tmp/pip-HuQpRE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:30:15 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)) 18:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:30:15 INFO - Running setup.py (path:/tmp/pip-EWou9_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:30:15 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)) 18:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:30:15 INFO - Running setup.py (path:/tmp/pip-WgmQvl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:30:15 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)) 18:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:30:15 INFO - Running setup.py (path:/tmp/pip-bBxrsQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:30:15 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)) 18:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:30:15 INFO - Running setup.py (path:/tmp/pip-trA8gS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:30:15 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)) 18:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:30:15 INFO - Running setup.py (path:/tmp/pip-fPNPsJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:30:15 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)) 18:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:30:15 INFO - Running setup.py (path:/tmp/pip-0qDtrU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:30:15 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)) 18:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:30:15 INFO - Running setup.py (path:/tmp/pip-yeQrRf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:30:15 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)) 18:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:30:15 INFO - Running setup.py (path:/tmp/pip-chDNzb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:30:15 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)) 18:30:15 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)) 18:30:15 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)) 18:30:15 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)) 18:30:15 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)) 18:30:15 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)) 18:30:15 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)) 18:30:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:30:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:30:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:30:16 INFO - Downloading blessings-1.6.tar.gz 18:30:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:30:16 INFO - Installing collected packages: blessings 18:30:16 INFO - Running setup.py install for blessings 18:30:17 INFO - Successfully installed blessings 18:30:17 INFO - Cleaning up... 18:30:17 INFO - Return code: 0 18:30:17 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:30:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:30:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:30:17 INFO - Reading from file tmpfile_stdout 18:30:17 INFO - Current package versions: 18:30:17 INFO - argparse == 1.2.1 18:30:17 INFO - blessings == 1.6 18:30:17 INFO - blobuploader == 1.2.4 18:30:17 INFO - docopt == 0.6.1 18:30:17 INFO - functools32 == 3.2.3-2 18:30:17 INFO - jsonschema == 2.5.1 18:30:17 INFO - manifestparser == 1.1 18:30:17 INFO - mozInstall == 1.13 18:30:17 INFO - mozcrash == 1.0 18:30:17 INFO - mozdebug == 0.1 18:30:17 INFO - mozdevice == 0.51 18:30:17 INFO - mozfile == 1.2 18:30:17 INFO - mozhttpd == 0.7 18:30:17 INFO - mozinfo == 0.10 18:30:17 INFO - mozleak == 0.1 18:30:17 INFO - mozlog == 3.5 18:30:17 INFO - moznetwork == 0.27 18:30:17 INFO - mozprocess == 0.25 18:30:17 INFO - mozprofile == 0.28 18:30:17 INFO - mozrunner == 6.13 18:30:17 INFO - mozscreenshot == 0.1 18:30:17 INFO - mozsystemmonitor == 0.3 18:30:17 INFO - moztest == 0.8 18:30:17 INFO - mozversion == 1.4 18:30:17 INFO - psutil == 3.1.1 18:30:17 INFO - requests == 1.2.3 18:30:17 INFO - wsgiref == 0.1.2 18:30:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:30:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:30:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:30:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:17 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 0x222d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23faa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x259dd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x259aeb0>, '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 0x259bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x259c240>, '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': '3a3620fb28aea1604ab609ef000001d2-1501550826.510254-1621757349', '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 18:30:17 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 18:30:17 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 18:30:17 INFO - Using env: {'DISPLAY': ':0', 18:30:17 INFO - 'HOME': '/home/cltbld', 18:30:17 INFO - 'LANG': 'en_US.UTF-8', 18:30:17 INFO - 'LANGUAGE': 'en_US:en', 18:30:17 INFO - 'LOGNAME': 'cltbld', 18:30:17 INFO - 'MAIL': '/var/mail/cltbld', 18:30:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:17 INFO - 'MOZ_NO_REMOTE': '1', 18:30:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:30:17 INFO - 'NO_EM_RESTART': '1', 18:30:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:30:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:17 INFO - 'PWD': '/builds/slave/test', 18:30:17 INFO - 'SHELL': '/bin/bash', 18:30:17 INFO - 'SHLVL': '1', 18:30:17 INFO - 'TERM': 'linux', 18:30:17 INFO - 'TMOUT': '86400', 18:30:17 INFO - 'USER': 'cltbld', 18:30:17 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1501550826.510254-1621757349', 18:30:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:17 INFO - '_': '/tools/buildbot/bin/python'} 18:30:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:17 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)) 18:30:17 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)) 18:30:17 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)) 18:30:17 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)) 18:30:17 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)) 18:30:17 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)) 18:30:17 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)) 18:30:17 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)) 18:30:17 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)) 18:30:17 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:30:17 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 18:30:17 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 18:30:17 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 18:30:17 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 18:30:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 18:30:18 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)) 18:30:18 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)) 18:30:18 INFO - Installing collected packages: simplejson 18:30:18 INFO - Running setup.py install for simplejson 18:30:18 INFO - building 'simplejson._speedups' extension 18:30:18 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 18:30:19 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 18:30:19 INFO - Successfully installed simplejson 18:30:19 INFO - Cleaning up... 18:30:19 INFO - Return code: 0 18:30:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:30:19 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')]} 18:30:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:19 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')]} 18:30:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x222d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23faa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x259dd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x259aeb0>, '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 0x259bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x259c240>, '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': '3a3620fb28aea1604ab609ef000001d2-1501550826.510254-1621757349', '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 18:30:19 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 18:30:19 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 18:30:19 INFO - Using env: {'DISPLAY': ':0', 18:30:19 INFO - 'HOME': '/home/cltbld', 18:30:19 INFO - 'LANG': 'en_US.UTF-8', 18:30:19 INFO - 'LANGUAGE': 'en_US:en', 18:30:19 INFO - 'LOGNAME': 'cltbld', 18:30:19 INFO - 'MAIL': '/var/mail/cltbld', 18:30:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:19 INFO - 'MOZ_NO_REMOTE': '1', 18:30:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:30:19 INFO - 'NO_EM_RESTART': '1', 18:30:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:30:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:19 INFO - 'PWD': '/builds/slave/test', 18:30:19 INFO - 'SHELL': '/bin/bash', 18:30:19 INFO - 'SHLVL': '1', 18:30:19 INFO - 'TERM': 'linux', 18:30:19 INFO - 'TMOUT': '86400', 18:30:19 INFO - 'USER': 'cltbld', 18:30:19 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1501550826.510254-1621757349', 18:30:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:19 INFO - '_': '/tools/buildbot/bin/python'} 18:30:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:19 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:30:19 INFO - Cleaning up... 18:30:19 INFO - Return code: 0 18:30:19 INFO - Running post-action listener: _resource_record_post_action 18:30:19 INFO - Running post-action listener: _start_resource_monitoring 18:30:20 INFO - Starting resource monitoring. 18:30:20 INFO - [mozharness: 2017-08-01 01:30:20.024574Z] Finished create-virtualenv step (success) 18:30:20 INFO - [mozharness: 2017-08-01 01:30:20.025637Z] Running install step. 18:30:20 INFO - Running pre-action listener: _resource_record_pre_action 18:30:20 INFO - Running main action method: install 18:30:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:30:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:30:20 INFO - Reading from file tmpfile_stdout 18:30:20 INFO - Detecting whether we're running mozinstall >=1.0... 18:30:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:30:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:30:20 INFO - Reading from file tmpfile_stdout 18:30:20 INFO - Output received: 18:30:20 INFO - Usage: mozinstall [options] installer 18:30:20 INFO - Options: 18:30:20 INFO - -h, --help show this help message and exit 18:30:20 INFO - -d DEST, --destination=DEST 18:30:20 INFO - Directory to install application into. [default: 18:30:20 INFO - "/builds/slave/test"] 18:30:20 INFO - --app=APP Application being installed. [default: firefox] 18:30:20 INFO - mkdir: /builds/slave/test/build/application 18:30:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 18:30:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 18:30:38 INFO - Reading from file tmpfile_stdout 18:30:38 INFO - Output received: 18:30:38 INFO - /builds/slave/test/build/application/firefox/firefox 18:30:38 INFO - Running post-action listener: _resource_record_post_action 18:30:38 INFO - [mozharness: 2017-08-01 01:30:38.448036Z] Finished install step (success) 18:30:38 INFO - [mozharness: 2017-08-01 01:30:38.448378Z] Running setup-mitmproxy step. 18:30:38 INFO - Running pre-action listener: _resource_record_pre_action 18:30:38 INFO - Running main action method: setup_mitmproxy 18:30:38 INFO - Skipping: mitmproxy is not required 18:30:38 INFO - Running post-action listener: _resource_record_post_action 18:30:38 INFO - [mozharness: 2017-08-01 01:30:38.450102Z] Finished setup-mitmproxy step (success) 18:30:38 INFO - [mozharness: 2017-08-01 01:30:38.450414Z] Running run-tests step. 18:30:38 INFO - Running pre-action listener: _resource_record_pre_action 18:30:38 INFO - Running pre-action listener: _set_gcov_prefix 18:30:38 INFO - Running main action method: run_tests 18:30:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:30:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:30:38 INFO - Python 2.7.3 18:30:38 INFO - Return code: 0 18:30:38 INFO - grabbing minidump binary from tooltool 18:30:38 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')]} 18:30:38 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 0x259aeb0>, '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 0x259bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x259c240>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:30:38 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 18:30:38 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 18:30:38 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 18:30:38 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:30:38 INFO - Return code: 0 18:30:38 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:30:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:30:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:30:38 INFO - ENV: RUST_BACKTRACE is now 1 18:30:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:30:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:30:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZbeB3PV6RPy_XbcR57i83g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-059', '--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 18:30:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ZbeB3PV6RPy_XbcR57i83g/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-059 --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 18:30:38 INFO - Using env: {'DISPLAY': ':0', 18:30:38 INFO - 'HOME': '/home/cltbld', 18:30:38 INFO - 'LANG': 'en_US.UTF-8', 18:30:38 INFO - 'LANGUAGE': 'en_US:en', 18:30:38 INFO - 'LOGNAME': 'cltbld', 18:30:38 INFO - 'MAIL': '/var/mail/cltbld', 18:30:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:30:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:30:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:38 INFO - 'MOZ_NO_REMOTE': '1', 18:30:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:30:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:30:38 INFO - 'NO_EM_RESTART': '1', 18:30:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:30:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:38 INFO - 'PWD': '/builds/slave/test', 18:30:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:30:38 INFO - 'RUST_BACKTRACE': '1', 18:30:38 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:30:38 INFO - 'SHELL': '/bin/bash', 18:30:38 INFO - 'SHLVL': '1', 18:30:38 INFO - 'TERM': 'linux', 18:30:38 INFO - 'TMOUT': '86400', 18:30:38 INFO - 'USER': 'cltbld', 18:30:38 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1501550826.510254-1621757349', 18:30:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:38 INFO - '_': '/tools/buildbot/bin/python'} 18:30:38 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZbeB3PV6RPy_XbcR57i83g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-059', '--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 18:30:38 INFO - mozversion application_buildid: 20170801004347 18:30:38 INFO - mozversion application_changeset: 44121dbcac6a9d3ff18ed087a09b3205e5a04db1 18:30:38 INFO - mozversion application_display_name: Nightly 18:30:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:30:38 INFO - mozversion application_name: Firefox 18:30:38 INFO - mozversion application_remotingname: firefox 18:30:38 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:30:38 INFO - mozversion application_vendor: Mozilla 18:30:38 INFO - mozversion application_version: 56.0a1 18:30:38 INFO - mozversion platform_buildid: 20170801004347 18:30:38 INFO - mozversion platform_changeset: 44121dbcac6a9d3ff18ed087a09b3205e5a04db1 18:30:38 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:30:38 INFO - mozversion platform_version: 56.0a1 18:30:38 INFO - using testdate: 1501551038 18:30:38 INFO - actual date: 1501551038 18:30:38 INFO - starting webserver on 'localhost:50688' 18:30:38 INFO - SUITE-START | Running 2 tests 18:30:38 INFO - TEST-START | dromaeo_css 18:30:38 INFO - Initialising browser for dromaeo_css test... 18:30:38 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:50688/getInfo.html -profile /tmp/tmphMYXRi/profile 18:30:38 INFO - TEST-INFO | started process 3315 (/builds/slave/test/build/application/firefox/firefox http://localhost:50688/getInfo.html) 18:30:46 INFO - TEST-INFO | 3315: exit 0 18:30:46 INFO - Browser initialized. 18:30:46 INFO - Running cycle 1/1 for dromaeo_css test... 18:30:46 INFO - TEST-INFO | started process 3521 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphMYXRi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:30:47 INFO - PID 3521 | 18:30:48 INFO - PID 3521 | (/builds/slave/test/build/application/firefox/firefox:3569): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:30:48 INFO - PID 3521 | 18:30:57 INFO - PID 3521 | RSS: Main: 171577344 18:30:57 INFO - PID 3521 | 18:33:57 INFO - PID 3521 | Cycle 1(1): loaded http://localhost:50688/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50688/tests/dromaeo/cssquery-ext.html) 18:33:59 INFO - PID 3521 | RSS: Main: 173518848 18:33:59 INFO - PID 3521 | 18:37:00 INFO - PID 3521 | Cycle 1(1): loaded http://localhost:50688/tests/dromaeo/cssquery-ext.html (next: http://localhost:50688/tests/dromaeo/cssquery-jquery.html) 18:37:01 INFO - PID 3521 | RSS: Main: 171065344 18:37:01 INFO - PID 3521 | 18:40:03 INFO - PID 3521 | Cycle 1(1): loaded http://localhost:50688/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50688/tests/dromaeo/cssquery-mootools.html) 18:40:03 INFO - PID 3521 | RSS: Main: 172945408 18:40:03 INFO - PID 3521 | 18:43:05 INFO - PID 3521 | Cycle 1(1): loaded http://localhost:50688/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50688/tests/dromaeo/cssquery-prototype.html) 18:43:06 INFO - PID 3521 | RSS: Main: 171839488 18:43:06 INFO - PID 3521 | 18:46:10 INFO - PID 3521 | Cycle 1(1): loaded http://localhost:50688/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50688/tests/dromaeo/cssquery-yui.html) 18:46:10 INFO - PID 3521 | RSS: Main: 175046656 18:46:10 INFO - PID 3521 | 18:49:12 INFO - PID 3521 | Cycle 1(1): loaded http://localhost:50688/tests/dromaeo/cssquery-yui.html (next: http://localhost:50688/tests/dromaeo/cssquery-dojo.html) 18:49:12 INFO - PID 3521 | RSS: Main: 175362048 18:49:12 INFO - PID 3521 | 18:49:12 INFO - PID 3521 | __start_tp_report 18:49:12 INFO - PID 3521 | _x_x_mozilla_page_load 18:49:12 INFO - PID 3521 | _x_x_mozilla_page_load_details 18:49:12 INFO - PID 3521 | |i|pagename|runs| 18:49:12 INFO - PID 3521 | |0;dojo.html;3143.088504333742;3166.6833316668335;3186.187522181843;3196.92902877536;3207.951880721791;6258;6263.217097862767;6266.498680105592;6300.684965751706;6316.873662526747;6105.572609917308;6194.814155575329;6200.906986395196;6201.720922558485;6217.285012223596;5658.122990936401;5670.121131224667;5682;5688.516476099516;5714.628549144313;5684.2326285951485;5729.541636669056;5753.683547404891;5786.450287222707;5797.739101740411;5669.518090962273;5751.712414379264;5753;5764.7117644117625;5802.506786923117;5488.313960754906;5616.073347897592;5699.059655156894;5735.713214339266;5763.193152958589;5132.897342053138;5613.7193140342815;5619.325680918275;5667.4615911488345;5699.8005069822775;6093.451589356979;6100.267967843887;6127.662978536178;6171.197744293258;6227.252729672423;6514.120593719835;6612.86774264512;6617.966910165466;6633.933660663428;6685.431738302202;6643.103181070542;6695.799126026227;6697;6704.865902681919;6710.194776626788;6161.106639537332;6208.2239720034995;6209.751609935643;6220.937790622081;6224.502039836802;6951.582905025613;6957.304269573002;6964.477664175106;6971.930280697229;6979.4416446684145;6510.218773747086;6609.041688955074;6617.602943823386;6653.234877988996;6674.16572928384;6656.001599760075;6669.199696036507;6742.292059333798;6752.75074111291;6763.188417389846;6606.8017959461295;6626.635535045522;6671.13275274221;6722.226943901416;6724.092247546617;6565.376289252514;6604.669766511655;6610.504212184105;6619.569727967667;6640.435562977201;5376.704281264568;5397.784088636489;5399.136138217867;5400;5401.7299135043095;4647.256438969748;4714.245720684743;4726.503717109723;4752.097101550694;4755.976220118877;5751.539876809845;5839.795607153814;5842.737076831553;5846.1523079153285;5846.152307915413;6695.966520167465;6698.263191048981;6720.596764194066;6799.694013769392;6809;5704.80033198836;5736.368999410061;5767.798127065533;5795;5799.2460980073165;5818.301803783573;5905.025670764362;5915.438033386821;5921.940780592225;5938.049912014057;6392.105105285264;6488.837779055561;6494.545381823227;6496.447801936888;6497.577657452251;6641.970494573349;6647.833804154935;6744.022116793136;6750.426213771884;6774.187097548326;6393.6483493408305;6404.647744374104;6474.805755827333;6498.447631951337;6581.703823327866;6394.776182833579;6522.543421960418;6527.51043671736;6530.314316996837;6549.639769812516;6627.768028119185;6632.1709786276715;6700.530962832555;6705.228898676714;6705.396514313735;6449.419552240321;6500.382463665944;6528.640924749185;6535.509836762357;6553.2136143661155;6432.742690292336;6457.61254324742;6540.607563546202;6546.770863019771;6548.148740663683;6323.841903952438;6370.522210834113;6380.457661098859;6397.3282805306635;6456.54804163704;6466.385693359123;6518.674066296761;6561.770338038145;6570.704318305592;6573.934260657332;6312.9899216125195;6335;6336.017917222838;6341.55609107358;6370 18:49:12 INFO - PID 3521 | |1;ext.html;749.7001199520191;837.225566351125;859.7635650196197;860.7288704058217;1123.2474242257697;29355.119346419615;29856.402871942548;39137.60862391375;40081.59918400815;40093.398599021035;39414;39731;39762;39779.40330895039;39802.40296395557;7468.290512401327;7621.504602200867;7632.389408847307;7642.121156067068;7648.923510764905;7391.445641576876;7451.962740186292;7556.546607203558;7562.319391254786;7565;7542.585157816318;7551.811204719871;7561.508501947384;7564.583947882864;7588.468807183499;7452.478326517146;7552.207018263086;7568.470207085506;7576.128745194318;7578.317951384347;7446.553206807609;7475.476716629838;7482.850342993164;7494.213107623676;7503.212162722944;35666;35840.820795896114;35856.462153067725;35858.28283434343;35913.64086359129;3536.169000284931;3543.787372757643;3571.196480791827;3573.10672331917;3579.6420357964257;29975.550366744515;29997.850010749808;30061;30064.248393790112;30278.697213027808;4674.7428891410955;4698.3187437821325;4744.691595046311;4767.499412561666;4830.444498882638;209453;210757;211953.9402302994;212675;213214;46653.76673116613;46898.296525551465;46906.76546617245;46956.29565556519;46977;48806.51193488091;48880.26679599809;51662.483375165764;52514.21228681573;52785.2082218767;14860.18268995194;14917.627059323386;14995.32521036556;15044.398224070914;15078;10328.845067323997;12092.093093017882;12125.333106679218;12136.635900922987;12141.75716485683;12579.811302830465;13615.251161186032;13665.453381864614;13997;14003.089799163023;5559.527440167631;5718.885622287614;5743.62666426681;5827.484853937922;5830.212921255661;974.8099120671543;976.4190306767462;984.3454103021554;1017.3539802225492;1026.296986564191;21689.566208675737;21733.45666358322;22260.55478890413;22380.664290035664;22742.65886011711;1074.9731256718644;1085.8154113800515;1097.7749561339906;1120.8935151160626;1121.859767529059;11589.594364197214;11807.40962951849;12093;12182.208156469802;12298.631041068782;207812;207986;208206;210255;210533;49438.50561494411;49725;49972.75013624909;50151;50271.49728502668;1416.504223521759;1423.736608727368;1426.8216472940883;1430.5064752660523;1469.80157678712;21274.468138296048;22079.558408832054;22112.33662990113;22549;25858.870705647103;49047.264291034946;49605.50394496009;49627.25559116688;49676;49679.75160124176;193938;196270;196771;196791;197169;32480.35039299248;33545.329093418484;33650.83174584111;33709.662903370656;33974;40574.18851622892;41467.1706565873;41644.791776040925;41692.79153604334;41913.16173676449;28755.13734587966;29580.55629165521;29860;30294.394112118076;30389.84805076049;27596.724032759415;28062.579061314504;29828.403431931674;30310.242243944078;30501.54247686331;33895.32209355848;33961.320773584885;33993.83003084969;34013.82993085019;34021;29019.41961160807;29117.417651646425;30430.54354184733;30697.386052278383;31211.687883121787;21885.234016809336;22886.42783930415;22922.77077229273;23102.537949241258;23273.650895236246 18:49:12 INFO - PID 3521 | |2;jquery.html;563.1777604697141;575.1660092865344;580.747374891922;584.880099579586;595.982120536384;21595.568088638258;21725.565488690238;22362;22415.66376504352;22572.54854902903;25317.2404827855;25887.870560647218;25888.223353299385;25968.610470842952;25977.480450391027;6419.197600299963;6625.0393692914495;6630.602163870183;6655.567388119781;6656.567323124005;6121.540884433663;6470.935290647104;6570.605763654196;6621.404073633372;6633.4693224541925;6401.295857455676;6401.647909364983;6405.29541750407;6405.42351188393;6427.678616069201;6397.5521713480075;6410.67946602668;6425.100485931974;6433.356664333576;6434.742610295582;5986.28164620244;6007.429294217042;6013.729382177812;6016.2479690038745;6019.759209631609;26279;28579.428411431654;28780.568291475643;29087.41825163506;29271;1856.6286742651523;1918.0409795102448;1919.9808001919941;1920.5582715975265;1922.826945574905;18732.15705293265;18915.24339026437;19059.809401905943;19103.426897193105;19104.904475477535;2281.5779080870093;2305.561943230781;2326.8371214015024;2328.2316835444262;2371.91698290559;137833;141149;141431;141828.58171418257;142055;13899.583012509438;14461.638459038399;14489.20309382973;14807.70384592302;15066.472673456596;22378.888105559367;22832.31503054911;22905.541889162123;22956.426089347566;23014.309570712907;9853.704388868346;10013.299069065095;10120.595176193017;10125.949370253249;10207.183425326104;9001.864972025425;9058.275337972946;9084.40951338161;9231.861522077174;9246.214071803837;9051.456912585267;9185.448873067637;9259.76850578741;9394.765130871783;9637;2664.826786258926;2672.425428532875;2739.3836386813205;2767.3911739417294;2867.8709458074436;961.2166084640995;963.5113749257414;966.3622009473713;978.0806042320196;985.8570507276405;19437.708434373497;19857.503562411057;22682.546349072927;24444.511109777704;24702.752972470043;1028.5114570578914;1031.3141760729031;1061.1192710050793;1061.623123791043;1068.8396740488834;10759.677209683721;11044.33733975964;11222.719432014264;11263.774724505627;11330.674582946001;500624;506850;511892;513725;518159;116838;117706.41146794212;122795.85148475324;123041;123266.3836680811;1241.74544218435;1253.1290752926811;1292.1665525735848;1297.6366617347346;1299.2853930338276;19728.802711972698;20632;20669.379918602466;21273.36179914605;21538.030788614862;48893.511064888895;49480.25779613237;56652.716736416056;59110.704446477495;60317;65063.349366507624;65217;65475.34524654883;65552.67223663851;66076.33923660702;14606;14789.926050369679;14985;15018.774718379014;15019.173945433105;22982.540349193256;23410.882945585163;23462.765372346057;23710.407239819142;23724.28827135189;18184.45446636603;18312.26750929948;18529.90735046316;18570.628587427906;18647.72028419602;15465;15967.281472333541;16067.598310042342;16281.267342969832;16622.418215362406;18565.907170464063;18957;19083.90458047701;19114.330998415546;19145.32991345352;14706;14774;14783.113013219241;14803.297620773317;14857.851421486079;17350.39273625417;18398.540036499195;18541.35105271309;18649.347272845385;18665.81334186695 18:49:12 INFO - PID 3521 | |3;mootools.html;1327.7941919002556;1366.2690460603578;1378.951736689216;1392.8369811207642;1396.9650758731027;13508.797368039475;14106.153630782148;14108.788368174486;14130.788038179435;14136.575902722909;11893.345865977368;12329.445174967126;12376.566820161295;13678.794818077737;14269.571912842606;2660.481206164794;2736.315921019745;2738.657667791526;2739.4384151248946;2740.5341092014405;2630.06632645411;2662.5340565401075;2739.2740923655247;2742.520058989669;2744.8490333031677;2745.135282386042;2788.428372183708;2789.302674331417;2794.3433293176085;2797.7202279772064;2612.673415823022;2652.6551548298694;2659.7473240042164;2661.2415461593355;2712.2541301142246;2650.3506640872915;2654.1241390341143;2672.291842661696;2675.598660200966;2683.409649877024;13713;13966.93016534921;13986.580402587939;14013;14029.228392438514;7455.589942553157;7716.845663086763;7743.303102720726;7745.767626971144;7769.572673502955;5020.347354843883;5116.590672746172;5153.098207813618;5246.422893481714;5290.814821481268;1825.9269629214816;1833.4499650104915;1839.420582516503;1867.3184287735014;1883.0772921268617;22647;22836.4290892729;22838.657420138876;22839.543209135725;22855.542889142125;6486.610803351814;6526.347365263436;6629.668516574248;6664.5334826561675;6752.155980502437;15533.145676987875;15543.378264869278;15547.611309717122;15549.689006219813;15594.362591927911;7453.180150183475;7607.733729319558;7611.010568626043;7618.123915749649;7630.2751238632245;6971.58170509771;6987.196472405637;7065.89401158983;7097.7160913562875;7144.749933752294;4896.363472748591;5127.974405118991;5139.254808052886;5151.201563757623;5196.92204616931;1407.4440595964536;1437.547172640615;1464.9414023438942;1472.7201831652167;1480.25247250138;830.7009476588423;836.2139588786521;853.7182729699184;863.53369180643;871.6252011634931;7313.89029164563;7470.551766893895;7658.693652253847;7700.422468314899;7741.800020996755;923.8752768376318;945.8439357506072;954.3081266082173;966.4056605187759;980.8528720691953;4268.743875367488;4857.611391088704;4884.291777692286;5000.449950505442;5137.974310128426;18474;18739.34412295563;19309;19564.119614617375;19575.314863979693;18314.267429302945;18819.52951176204;19263.22947082101;19383.127759250867;19384.127714252892;10525.052745252964;10897.455127243606;10955.780884382428;10960.342379457257;10982.670519884416;7019.333163349474;7427.145878224073;7440.7023719052795;7643.7324693635455;7734.342580880463;16409.753853691964;16485.258163382437;16486.505404837873;16703.248353823863;17693.20380582851;16903.830961690226;17204.13979301055;17263.22315495831;17361.913190433967;17668.381606643707;11187.440627968732;11479.368634724837;11560.6531804046;11562.248453850809;11570.19008669385;12442.37788110609;12538.561150359694;12601.747965040831;12683.936580317039;12786.424610892345;9422.387544809566;11240.325580465198;11265.493052812806;11595.768084638428;11709.180357375242;10081.798364032826;10082.596696132072;10298.588056477956;10347.741306467398;10607.469626518488;8864.911350886408;9087;9192.95403522978;9240.491772952551;9255.398399104037;6554.279029306867;6565.376289252514;6735.461163106842;6890.86300760389;6984.126984126984;6373.936260637334;6547.476201903932;6685.465162787063;6807.217170025312;6831.760888368884 18:49:12 INFO - PID 3521 | |4;prototype.html;494.10073665928024;497.51989330296254;521.6609204017388;528.2182370092265;533.0458479322014;6045.183900173475;6085.908711369333;6246.3753624637575;6247.281562620295;6297.244330680313;5985.221921150244;5988.670623115727;6030.66831324277;6088.939110608892;6102.725377358019;4864.805407783684;4909.7545122743895;4917.50824917509;4919.43426505951;4958.429780575226;4854.708717476945;4892.3884514435695;4923.55687988081;4937.481564435737;4988.950110498903;4714.764261786914;4841.2480327745925;4846.515348465161;4859.246816743411;4913.606911447076;4846.975765121187;4895.388076490439;4896.951030489703;4949.985253023122;4978.278149668314;4413.823447062114;4447.444069491314;4450.554944505556;4458.331250312447;4482.238019536687;6063.393660633901;6104.344610094623;6111.663858487781;6114.847128821771;6145.201123853871;5721.9427805721825;5730.710590117232;5738.799142030009;5749.367569567345;5777.335606405275;6504.479641628705;6524.934750652527;6531.4448271896945;6605.504587155982;6610.834729131762;4836.975815120902;4864.318995340655;4868.269759536062;4872.926906096412;4879.731614761186;6867.484938629623;6937.549059311179;6952.965235173791;6984.3364880336285;6994.230634630187;4929.112759703288;4950.232713929347;4973.403191616957;4987.077390682735;4998.825041123543;5033.6224783141415;5057.949420505822;5063.392392912874;5071.163258062378;5074.594032477393;5689.890471358045;5703.287089113861;5711.771529138871;5731.8219076750565;5753.971230143822;6555.4427873631275;6557.3442655735;6565.310642382577;6576.040781648182;6676.46588272937;1654.288655877984;1719.6732620802009;1728.4382575662707;1740.8945319722068;1766.9469915902544;1183.3432444992939;1198.406788639629;1202.22456515547;1221.3587866370226;1227.2881728597392;5660.688662123623;5705.400932902068;5731.369549349568;5744.253247077937;5778.653280803165;6503.366136714638;6536.379043990813;6542.443892269211;6652.900206496906;6676.833079172962;5708.429157084342;5779.624324418899;5792.739326730307;5793.333766616809;5825.242718446575;3521.7358698097746;3543.511725075423;3558.946615800765;3566.6803282785536;3577.767445116059;6461.870762584816;6485.156929599122;6513.218413790375;6526.510511711641;6557.213134423804;6851.2121106072445;6883.208431030395;6912.619805910723;6917.481188910751;6954.374106330454;6411.6153030818305;6439.806805795927;6440.259370172396;6481.282460148064;6491.545591808528;6578.750037492861;6656.167979002625;6661.666916654245;6761.425278851353;6794.3545363190415;6647.102641143384;6665.600063996176;6671.199456065303;6695.4978376620975;6742.42689371409;6399.360063993563;6488.988413591802;6491.709256955566;6492.156019717407;6563.1796025496815;4928.211486162197;4938.1112698429015;4941.579965702956;4948.307236986752;4961.900761984668;6643.900341494785;6678.83302917431;6699.363560461748;6754.527183097332;6776.966115169393;6462.676866156579;6506.633606942595;6516.609003459807;6558.639274839929;6560.884649609482;6371.164831649871;6379.851626707237;6455.289918109098;6462.7414903405215;6499.837504062436;5879.412058794086;6065.181200538047;6313.305536391085;6353.664326228767;6391.296957334783;6394.392532709385;6403.51973601991;6420.6147631142285;6428.585711143541;6559.7376104955265;6280.371962803683;6326.272478664829;6355.618662880242;6357.96821015892;6378 18:49:12 INFO - PID 3521 | |5;yui.html;892.5135800988461;897.80248345364;917.9947956987105;918.3066784577645;927.6845872403386;17868.82131178688;18698.15858286377;18718.438446846583;18809.153588088517;18927.526811829695;19550.608987820237;20784.792152078473;20822.375328740123;20827.375178744664;20843;1015.3653966271099;1026.563710423071;1026.8767747870265;1033.1476531861208;1034.8085604163216;958.0611001218809;1018.8777346718405;1032.6592224565875;1048.093399209682;1055.0662663542782;1083.3229231730168;1084.3331351219022;1084.614961688601;1084.815581351172;1087.141158484796;1067.0983019348678;1067.6209945469402;1073.6725298783867;1076.4725284610563;1078.1698092468794;787.4999375396607;789.388224126301;789.6525528767324;792.8176519400513;797.0634504457297;18542.44372668822;18708.812911870842;18866.52833679169;18868.15093320804;19544.80455195444;20304.69542956857;20584.79415205859;20717.482062948395;21095.367138985854;21120.471988200265;15349.07905525672;15575;15664.765028524582;15878;15905.920470397574;3411.880584179542;3459.031471188046;3463.497792820027;3468.497067925137;3481.425564781808;115136.42431787787;118313;118893;119891.99999999913;123028.38485807514;15557.29992150356;15937.760933586005;16226.107564084088;16230.918845405933;16244.431444899372;29361.85319073434;29430.852845736063;29473.11580652584;29518;29636;8054.838903221903;8462.661493540312;8496.49021058726;8506.149385061568;8598.914010859811;4130.091379896418;4202.390653355307;4204.957950420457;4211.4525111735275;4218.325067989107;4946.727929963887;5154.278401023859;5170.45710200431;5178.637495375363;5446.2647542582035;1135.9091272698165;1154.5439551377326;1155.2375432214699;1155.2779512804498;1159.1885680024018;627.645380360095;652.4063102576632;661.4807376209652;674.1472037872096;688.0985908459934;10647.680569582924;10724.195685323475;11162.386068766133;11361.261518001304;11457.484413201426;636.1729751323261;642.2068745099798;664.4750646988921;670.5105273150627;687.0278555843425;5344.91982620261;5444.646098003617;5486.588505861996;5496.093144631171;5598.244237027949;96283;96931.51534242283;99457;103489.48255258675;104496;89951.10048899558;91370.54314728384;92784;93246;95576.04423955671;73309.63345183346;76497;76532;77903.61048194722;78600;10730.517126729472;11271.154663400113;11492.82760758606;11886.881131188924;11991.22057066288;34544.136396590286;35520.644793551735;36112.47212695086;36402.27195456129;38694.80652596719;45554.31668524908;45964.54035459603;46822;47016.7649161752;47493.76253118712;16710;16929.830701693318;17355.47933561995;17393.82606173922;17437.04096274666;19163.137658805666;19442.319518816774;19514.121864515848;19691.901540492207;19716.309929152412;17275.481735547954;17707.822921770618;17842;17851.73222401691;17880.105994699952;18041.09794510243;18218.18018189158;18313;18332.908335458236;18339.816601834344;17107.401240956508;17360.21879015422;17443.040632765318;17470;17492.212850422016;10991.835122473009;11406.258593191416;11545.769084618429;11783.823242651195;11938.343391113573;11572.94213528927;12152.513899443924;12278.570250041206;12358.50565977351;12464.910708952793 18:49:12 INFO - PID 3521 | __end_tp_report 18:49:12 INFO - PID 3521 | __start_cc_report 18:49:12 INFO - PID 3521 | _x_x_mozilla_cycle_collect,361 18:49:12 INFO - PID 3521 | __end_cc_report 18:49:12 INFO - PID 3521 | __startTimestamp1501552152706__endTimestamp 18:49:12 INFO - PID 3521 | 18:49:12 INFO - PID 3521 | ------- Summary: start ------- 18:49:12 INFO - PID 3521 | Number of tests: 6 18:49:12 INFO - PID 3521 | 18:49:12 INFO - PID 3521 | [#0] dojo.html Cycles:165 Average:6143.04 Median:6380.46 stddev:719.69 (11.3%) stddev-sans-first:682.33 18:49:12 INFO - PID 3521 | Values: 3143.1 3166.7 3186.2 3196.9 3208.0 6258.0 6263.2 6266.5 6300.7 6316.9 6105.6 6194.8 6200.9 6201.7 6217.3 5658.1 5670.1 5682.0 5688.5 5714.6 5684.2 5729.5 5753.7 5786.5 5797.7 5669.5 5751.7 5753.0 5764.7 5802.5 5488.3 5616.1 5699.1 5735.7 5763.2 5132.9 5613.7 5619.3 5667.5 5699.8 6093.5 6100.3 6127.7 6171.2 6227.3 6514.1 6612.9 6618.0 6633.9 6685.4 6643.1 6695.8 6697.0 6704.9 6710.2 6161.1 6208.2 6209.8 6220.9 6224.5 6951.6 6957.3 6964.5 6971.9 6979.4 6510.2 6609.0 6617.6 6653.2 6674.2 6656.0 6669.2 6742.3 6752.8 6763.2 6606.8 6626.6 6671.1 6722.2 6724.1 6565.4 6604.7 6610.5 6619.6 6640.4 5376.7 5397.8 5399.1 5400.0 5401.7 4647.3 4714.2 4726.5 4752.1 4756.0 5751.5 5839.8 5842.7 5846.2 5846.2 6696.0 6698.3 6720.6 6799.7 6809.0 5704.8 5736.4 5767.8 5795.0 5799.2 5818.3 5905.0 5915.4 5921.9 5938.0 6392.1 6488.8 6494.5 6496.4 6497.6 6642.0 6647.8 6744.0 6750.4 6774.2 6393.6 6404.6 6474.8 6498.4 6581.7 6394.8 6522.5 6527.5 6530.3 6549.6 6627.8 6632.2 6700.5 6705.2 6705.4 6449.4 6500.4 6528.6 6535.5 6553.2 6432.7 6457.6 6540.6 6546.8 6548.1 6323.8 6370.5 6380.5 6397.3 6456.5 6466.4 6518.7 6561.8 6570.7 6573.9 6313.0 6335.0 6336.0 6341.6 6370.0 18:49:12 INFO - PID 3521 | 18:49:12 INFO - PID 3521 | [#1] ext.html Cycles:180 Average:36932.22 Median:33967.66 stddev:53341.77 (157.0%) stddev-sans-first:53422.22 18:49:12 INFO - PID 3521 | Values: 749.7 837.2 859.8 860.7 1123.2 29355.1 29856.4 39137.6 40081.6 40093.4 39414.0 39731.0 39762.0 39779.4 39802.4 7468.3 7621.5 7632.4 7642.1 7648.9 7391.4 7452.0 7556.5 7562.3 7565.0 7542.6 7551.8 7561.5 7564.6 7588.5 7452.5 7552.2 7568.5 7576.1 7578.3 7446.6 7475.5 7482.9 7494.2 7503.2 35666.0 35840.8 35856.5 35858.3 35913.6 3536.2 3543.8 3571.2 3573.1 3579.6 29975.6 29997.9 30061.0 30064.2 30278.7 4674.7 4698.3 4744.7 4767.5 4830.4 209453.0 210757.0 211953.9 212675.0 213214.0 46653.8 46898.3 46906.8 46956.3 46977.0 48806.5 48880.3 51662.5 52514.2 52785.2 14860.2 14917.6 14995.3 15044.4 15078.0 10328.8 12092.1 12125.3 12136.6 12141.8 12579.8 13615.3 13665.5 13997.0 14003.1 5559.5 5718.9 5743.6 5827.5 5830.2 974.8 976.4 984.3 1017.4 1026.3 21689.6 21733.5 22260.6 22380.7 22742.7 1075.0 1085.8 1097.8 1120.9 1121.9 11589.6 11807.4 12093.0 12182.2 12298.6 207812.0 207986.0 208206.0 210255.0 210533.0 49438.5 49725.0 49972.8 50151.0 50271.5 1416.5 1423.7 1426.8 1430.5 1469.8 21274.5 22079.6 22112.3 22549.0 25858.9 49047.3 49605.5 49627.3 49676.0 49679.8 193938.0 196270.0 196771.0 196791.0 197169.0 32480.4 33545.3 33650.8 33709.7 33974.0 40574.2 41467.2 41644.8 41692.8 41913.2 28755.1 29580.6 29860.0 30294.4 30389.8 27596.7 28062.6 29828.4 30310.2 30501.5 33895.3 33961.3 33993.8 34013.8 34021.0 29019.4 29117.4 30430.5 30697.4 31211.7 21885.2 22886.4 22922.8 23102.5 23273.7 18:49:12 INFO - PID 3521 | 18:49:12 INFO - PID 3521 | [#2] jquery.html Cycles:180 Average:35422.94 Median:22930.98 stddev:85851.38 (374.4%) stddev-sans-first:86052.32 18:49:12 INFO - PID 3521 | Values: 563.2 575.2 580.7 584.9 596.0 21595.6 21725.6 22362.0 22415.7 22572.5 25317.2 25887.9 25888.2 25968.6 25977.5 6419.2 6625.0 6630.6 6655.6 6656.6 6121.5 6470.9 6570.6 6621.4 6633.5 6401.3 6401.6 6405.3 6405.4 6427.7 6397.6 6410.7 6425.1 6433.4 6434.7 5986.3 6007.4 6013.7 6016.2 6019.8 26279.0 28579.4 28780.6 29087.4 29271.0 1856.6 1918.0 1920.0 1920.6 1922.8 18732.2 18915.2 19059.8 19103.4 19104.9 2281.6 2305.6 2326.8 2328.2 2371.9 137833.0 141149.0 141431.0 141828.6 142055.0 13899.6 14461.6 14489.2 14807.7 15066.5 22378.9 22832.3 22905.5 22956.4 23014.3 9853.7 10013.3 10120.6 10125.9 10207.2 9001.9 9058.3 9084.4 9231.9 9246.2 9051.5 9185.4 9259.8 9394.8 9637.0 2664.8 2672.4 2739.4 2767.4 2867.9 961.2 963.5 966.4 978.1 985.9 19437.7 19857.5 22682.5 24444.5 24702.8 1028.5 1031.3 1061.1 1061.6 1068.8 10759.7 11044.3 11222.7 11263.8 11330.7 500624.0 506850.0 511892.0 513725.0 518159.0 116838.0 117706.4 122795.9 123041.0 123266.4 1241.7 1253.1 1292.2 1297.6 1299.3 19728.8 20632.0 20669.4 21273.4 21538.0 48893.5 49480.3 56652.7 59110.7 60317.0 65063.3 65217.0 65475.3 65552.7 66076.3 14606.0 14789.9 14985.0 15018.8 15019.2 22982.5 23410.9 23462.8 23710.4 23724.3 18184.5 18312.3 18529.9 18570.6 18647.7 15465.0 15967.3 16067.6 16281.3 16622.4 18565.9 18957.0 19083.9 19114.3 19145.3 14706.0 14774.0 14783.1 14803.3 14857.9 17350.4 18398.5 18541.4 18649.3 18665.8 18:49:12 INFO - PID 3521 | 18:49:12 INFO - PID 3521 | [#3] mootools.html Cycles:180 Average:8591.35 Median:2660.86 stddev:5852.65 (220.0%) stddev-sans-first:5843.62 18:49:12 INFO - PID 3521 | Values: 1327.8 1366.3 1379.0 1392.8 1397.0 13508.8 14106.2 14108.8 14130.8 14136.6 11893.3 12329.4 12376.6 13678.8 14269.6 2660.5 2736.3 2738.7 2739.4 2740.5 2630.1 2662.5 2739.3 2742.5 2744.8 2745.1 2788.4 2789.3 2794.3 2797.7 2612.7 2652.7 2659.7 2661.2 2712.3 2650.4 2654.1 2672.3 2675.6 2683.4 13713.0 13966.9 13986.6 14013.0 14029.2 7455.6 7716.8 7743.3 7745.8 7769.6 5020.3 5116.6 5153.1 5246.4 5290.8 1825.9 1833.4 1839.4 1867.3 1883.1 22647.0 22836.4 22838.7 22839.5 22855.5 6486.6 6526.3 6629.7 6664.5 6752.2 15533.1 15543.4 15547.6 15549.7 15594.4 7453.2 7607.7 7611.0 7618.1 7630.3 6971.6 6987.2 7065.9 7097.7 7144.7 4896.4 5128.0 5139.3 5151.2 5196.9 1407.4 1437.5 1464.9 1472.7 1480.3 830.7 836.2 853.7 863.5 871.6 7313.9 7470.6 7658.7 7700.4 7741.8 923.9 945.8 954.3 966.4 980.9 4268.7 4857.6 4884.3 5000.4 5138.0 18474.0 18739.3 19309.0 19564.1 19575.3 18314.3 18819.5 19263.2 19383.1 19384.1 10525.1 10897.5 10955.8 10960.3 10982.7 7019.3 7427.1 7440.7 7643.7 7734.3 16409.8 16485.3 16486.5 16703.2 17693.2 16903.8 17204.1 17263.2 17361.9 17668.4 11187.4 11479.4 11560.7 11562.2 11570.2 12442.4 12538.6 12601.7 12683.9 12786.4 9422.4 11240.3 11265.5 11595.8 11709.2 10081.8 10082.6 10298.6 10347.7 10607.5 8864.9 9087.0 9193.0 9240.5 9255.4 6554.3 6565.4 6735.5 6890.9 6984.1 6373.9 6547.5 6685.5 6807.2 6831.8 18:49:12 INFO - PID 3521 | 18:49:12 INFO - PID 3521 | [#4] prototype.html Cycles:180 Average:5478.02 Median:6064.29 stddev:1543.30 (25.4%) stddev-sans-first:1501.61 18:49:12 INFO - PID 3521 | Values: 494.1 497.5 521.7 528.2 533.0 6045.2 6085.9 6246.4 6247.3 6297.2 5985.2 5988.7 6030.7 6088.9 6102.7 4864.8 4909.8 4917.5 4919.4 4958.4 4854.7 4892.4 4923.6 4937.5 4989.0 4714.8 4841.2 4846.5 4859.2 4913.6 4847.0 4895.4 4897.0 4950.0 4978.3 4413.8 4447.4 4450.6 4458.3 4482.2 6063.4 6104.3 6111.7 6114.8 6145.2 5721.9 5730.7 5738.8 5749.4 5777.3 6504.5 6524.9 6531.4 6605.5 6610.8 4837.0 4864.3 4868.3 4872.9 4879.7 6867.5 6937.5 6953.0 6984.3 6994.2 4929.1 4950.2 4973.4 4987.1 4998.8 5033.6 5057.9 5063.4 5071.2 5074.6 5689.9 5703.3 5711.8 5731.8 5754.0 6555.4 6557.3 6565.3 6576.0 6676.5 1654.3 1719.7 1728.4 1740.9 1766.9 1183.3 1198.4 1202.2 1221.4 1227.3 5660.7 5705.4 5731.4 5744.3 5778.7 6503.4 6536.4 6542.4 6652.9 6676.8 5708.4 5779.6 5792.7 5793.3 5825.2 3521.7 3543.5 3558.9 3566.7 3577.8 6461.9 6485.2 6513.2 6526.5 6557.2 6851.2 6883.2 6912.6 6917.5 6954.4 6411.6 6439.8 6440.3 6481.3 6491.5 6578.8 6656.2 6661.7 6761.4 6794.4 6647.1 6665.6 6671.2 6695.5 6742.4 6399.4 6489.0 6491.7 6492.2 6563.2 4928.2 4938.1 4941.6 4948.3 4961.9 6643.9 6678.8 6699.4 6754.5 6777.0 6462.7 6506.6 6516.6 6558.6 6560.9 6371.2 6379.9 6455.3 6462.7 6499.8 5879.4 6065.2 6313.3 6353.7 6391.3 6394.4 6403.5 6420.6 6428.6 6559.7 6280.4 6326.3 6355.6 6358.0 6378.0 18:49:12 INFO - PID 3521 | 18:49:12 INFO - PID 3521 | [#5] yui.html Cycles:180 Average:21737.81 Median:19302.73 stddev:29135.29 (150.9%) stddev-sans-first:29174.97 18:49:12 INFO - PID 3521 | Values: 892.5 897.8 918.0 918.3 927.7 17868.8 18698.2 18718.4 18809.2 18927.5 19550.6 20784.8 20822.4 20827.4 20843.0 1015.4 1026.6 1026.9 1033.1 1034.8 958.1 1018.9 1032.7 1048.1 1055.1 1083.3 1084.3 1084.6 1084.8 1087.1 1067.1 1067.6 1073.7 1076.5 1078.2 787.5 789.4 789.7 792.8 797.1 18542.4 18708.8 18866.5 18868.2 19544.8 20304.7 20584.8 20717.5 21095.4 21120.5 15349.1 15575.0 15664.8 15878.0 15905.9 3411.9 3459.0 3463.5 3468.5 3481.4 115136.4 118313.0 118893.0 119892.0 123028.4 15557.3 15937.8 16226.1 16230.9 16244.4 29361.9 29430.9 29473.1 29518.0 29636.0 8054.8 8462.7 8496.5 8506.1 8598.9 4130.1 4202.4 4205.0 4211.5 4218.3 4946.7 5154.3 5170.5 5178.6 5446.3 1135.9 1154.5 1155.2 1155.3 1159.2 627.6 652.4 661.5 674.1 688.1 10647.7 10724.2 11162.4 11361.3 11457.5 636.2 642.2 664.5 670.5 687.0 5344.9 5444.6 5486.6 5496.1 5598.2 96283.0 96931.5 99457.0 103489.5 104496.0 89951.1 91370.5 92784.0 93246.0 95576.0 73309.6 76497.0 76532.0 77903.6 78600.0 10730.5 11271.2 11492.8 11886.9 11991.2 34544.1 35520.6 36112.5 36402.3 38694.8 45554.3 45964.5 46822.0 47016.8 47493.8 16710.0 16929.8 17355.5 17393.8 17437.0 19163.1 19442.3 19514.1 19691.9 19716.3 17275.5 17707.8 17842.0 17851.7 17880.1 18041.1 18218.2 18313.0 18332.9 18339.8 17107.4 17360.2 17443.0 17470.0 17492.2 10991.8 11406.3 11545.8 11783.8 11938.3 11572.9 12152.5 12278.6 12358.5 12464.9 18:49:12 INFO - PID 3521 | -------- Summary: end -------- 18:49:12 INFO - PID 3521 | 18:49:13 INFO - TEST-INFO | 3521: exit 0 18:49:13 INFO - TEST-OK | dromaeo_css | took 1114506ms 18:49:13 INFO - TEST-START | kraken 18:49:13 INFO - Initialising browser for kraken test... 18:49:13 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:50688/getInfo.html -profile /tmp/tmpnDzPlX/profile 18:49:13 INFO - TEST-INFO | started process 3878 (/builds/slave/test/build/application/firefox/firefox http://localhost:50688/getInfo.html) 18:49:18 INFO - TEST-INFO | 3878: exit 0 18:49:18 INFO - Browser initialized. 18:49:18 INFO - Running cycle 1/1 for kraken test... 18:49:18 INFO - TEST-INFO | started process 4087 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnDzPlX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:49:19 INFO - PID 4087 | 18:49:19 INFO - PID 4087 | (/builds/slave/test/build/application/firefox/firefox:4135): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:49:19 INFO - PID 4087 | 18:49:30 INFO - PID 4087 | RSS: Main: 171667456 18:49:30 INFO - PID 4087 | 18:49:32 INFO - PID 4087 | Cycle 1(1): loaded http://localhost:50688/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50688/tests/kraken/driver.html?testName=audio-beat-detection) 18:49:33 INFO - PID 4087 | RSS: Main: 176836608 18:49:33 INFO - PID 4087 | 18:49:36 INFO - PID 4087 | Cycle 1(1): loaded http://localhost:50688/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50688/tests/kraken/driver.html?testName=audio-dft) 18:49:37 INFO - PID 4087 | RSS: Main: 177000448 18:49:37 INFO - PID 4087 | 18:49:39 INFO - PID 4087 | Cycle 1(1): loaded http://localhost:50688/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50688/tests/kraken/driver.html?testName=audio-fft) 18:49:40 INFO - PID 4087 | RSS: Main: 171896832 18:49:40 INFO - PID 4087 | 18:49:42 INFO - PID 4087 | Cycle 1(1): loaded http://localhost:50688/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50688/tests/kraken/driver.html?testName=audio-oscillator) 18:49:43 INFO - PID 4087 | RSS: Main: 172695552 18:49:43 INFO - PID 4087 | 18:49:45 INFO - PID 4087 | Cycle 1(1): loaded http://localhost:50688/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50688/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:49:46 INFO - PID 4087 | RSS: Main: 172933120 18:49:46 INFO - PID 4087 | 18:49:49 INFO - PID 4087 | Cycle 1(1): loaded http://localhost:50688/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50688/tests/kraken/driver.html?testName=imaging-darkroom) 18:49:49 INFO - PID 4087 | RSS: Main: 175464448 18:49:49 INFO - PID 4087 | 18:49:53 INFO - PID 4087 | Cycle 1(1): loaded http://localhost:50688/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50688/tests/kraken/driver.html?testName=imaging-desaturate) 18:49:53 INFO - PID 4087 | RSS: Main: 176017408 18:49:53 INFO - PID 4087 | 18:49:56 INFO - PID 4087 | Cycle 1(1): loaded http://localhost:50688/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50688/tests/kraken/driver.html?testName=json-parse-financial) 18:49:57 INFO - PID 4087 | RSS: Main: 176054272 18:49:57 INFO - PID 4087 | 18:49:59 INFO - PID 4087 | Cycle 1(1): loaded http://localhost:50688/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50688/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:49:59 INFO - PID 4087 | RSS: Main: 176132096 18:49:59 INFO - PID 4087 | 18:50:01 INFO - PID 4087 | Cycle 1(1): loaded http://localhost:50688/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50688/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:50:02 INFO - PID 4087 | RSS: Main: 176369664 18:50:02 INFO - PID 4087 | 18:50:04 INFO - PID 4087 | Cycle 1(1): loaded http://localhost:50688/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50688/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:50:04 INFO - PID 4087 | RSS: Main: 173326336 18:50:04 INFO - PID 4087 | 18:50:07 INFO - PID 4087 | Cycle 1(1): loaded http://localhost:50688/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50688/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:50:08 INFO - PID 4087 | RSS: Main: 173662208 18:50:08 INFO - PID 4087 | 18:50:11 INFO - PID 4087 | Cycle 1(1): loaded http://localhost:50688/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50688/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:50:12 INFO - PID 4087 | RSS: Main: 173707264 18:50:12 INFO - PID 4087 | 18:50:13 INFO - PID 4087 | Cycle 1(1): loaded http://localhost:50688/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50688/tests/kraken/driver.html?testName=ai-astar) 18:50:14 INFO - PID 4087 | RSS: Main: 175407104 18:50:14 INFO - PID 4087 | 18:50:14 INFO - PID 4087 | __start_tp_report 18:50:14 INFO - PID 4087 | _x_x_mozilla_page_load 18:50:14 INFO - PID 4087 | _x_x_mozilla_page_load_details 18:50:14 INFO - PID 4087 | |i|pagename|runs| 18:50:14 INFO - PID 4087 | |0;ai-astar;100;107;103;107;109;110;109;103;108;112 18:50:14 INFO - PID 4087 | |1;audio-beat-detection;150;116;145;182;117;146;157;121;181;120 18:50:14 INFO - PID 4087 | |2;audio-dft;164;167;163;164;168;164;161;166;167;167 18:50:14 INFO - PID 4087 | |3;audio-fft;75;74;78;171;76;77;76;90;112;75 18:50:14 INFO - PID 4087 | |4;audio-oscillator;79;79;132;80;80;83;123;80;79;123 18:50:14 INFO - PID 4087 | |5;imaging-gaussian-blur;115;113;114;115;114;114;114;114;115;113 18:50:14 INFO - PID 4087 | |6;imaging-darkroom;150;151;158;151;151;150;151;151;151;151 18:50:14 INFO - PID 4087 | |7;imaging-desaturate;94;95;94;94;94;94;95;95;94;94 18:50:14 INFO - PID 4087 | |8;json-parse-financial;56;56;61;55;56;56;57;56;56;57 18:50:14 INFO - PID 4087 | |9;json-stringify-tinderbox;54;54;54;54;54;55;55;54;54;54 18:50:14 INFO - PID 4087 | |10;stanford-crypto-aes;93;102;80;82;88;79;79;82;91;80 18:50:14 INFO - PID 4087 | |11;stanford-crypto-ccm;133;127;132;128;147;127;137;126;126;160 18:50:14 INFO - PID 4087 | |12;stanford-crypto-pbkdf2;183;180;181;191;180;185;182;177;194;180 18:50:14 INFO - PID 4087 | |13;stanford-crypto-sha256-iterative;73;71;67;68;69;66;67;65;66;68 18:50:14 INFO - PID 4087 | __end_tp_report 18:50:14 INFO - PID 4087 | __start_cc_report 18:50:14 INFO - PID 4087 | _x_x_mozilla_cycle_collect,935 18:50:14 INFO - PID 4087 | __end_cc_report 18:50:14 INFO - PID 4087 | __startTimestamp1501552214142__endTimestamp 18:50:14 INFO - PID 4087 | 18:50:14 INFO - PID 4087 | ------- Summary: start ------- 18:50:14 INFO - PID 4087 | Number of tests: 14 18:50:14 INFO - PID 4087 | 18:50:14 INFO - PID 4087 | [#0] ai-astar Cycles:10 Average:106.80 Median:108.50 stddev:3.71 (3.4%) stddev-sans-first:3.00 18:50:14 INFO - PID 4087 | Values: 100.0 107.0 103.0 107.0 109.0 110.0 109.0 103.0 108.0 112.0 18:50:14 INFO - PID 4087 | 18:50:14 INFO - PID 4087 | [#1] audio-beat-detection Cycles:10 Average:143.50 Median:148.00 stddev:25.03 (16.9%) stddev-sans-first:26.44 18:50:14 INFO - PID 4087 | Values: 150.0 116.0 145.0 182.0 117.0 146.0 157.0 121.0 181.0 120.0 18:50:14 INFO - PID 4087 | 18:50:14 INFO - PID 4087 | [#2] audio-dft Cycles:10 Average:165.10 Median:166.50 stddev:2.23 (1.3%) stddev-sans-first:2.33 18:50:14 INFO - PID 4087 | Values: 164.0 167.0 163.0 164.0 168.0 164.0 161.0 166.0 167.0 167.0 18:50:14 INFO - PID 4087 | 18:50:14 INFO - PID 4087 | [#3] audio-fft Cycles:10 Average:90.40 Median:76.00 stddev:30.65 (40.3%) stddev-sans-first:32.00 18:50:14 INFO - PID 4087 | Values: 75.0 74.0 78.0 171.0 76.0 77.0 76.0 90.0 112.0 75.0 18:50:14 INFO - PID 4087 | 18:50:14 INFO - PID 4087 | [#4] audio-oscillator Cycles:10 Average:93.80 Median:79.50 stddev:22.38 (28.2%) stddev-sans-first:23.09 18:50:14 INFO - PID 4087 | Values: 79.0 79.0 132.0 80.0 80.0 83.0 123.0 80.0 79.0 123.0 18:50:14 INFO - PID 4087 | 18:50:14 INFO - PID 4087 | [#5] imaging-gaussian-blur Cycles:10 Average:114.10 Median:114.00 stddev:0.74 (0.6%) stddev-sans-first:0.71 18:50:14 INFO - PID 4087 | Values: 115.0 113.0 114.0 115.0 114.0 114.0 114.0 114.0 115.0 113.0 18:50:14 INFO - PID 4087 | 18:50:14 INFO - PID 4087 | [#6] imaging-darkroom Cycles:10 Average:151.50 Median:151.00 stddev:2.32 (1.5%) stddev-sans-first:2.40 18:50:14 INFO - PID 4087 | Values: 150.0 151.0 158.0 151.0 151.0 150.0 151.0 151.0 151.0 151.0 18:50:14 INFO - PID 4087 | 18:50:14 INFO - PID 4087 | [#7] imaging-desaturate Cycles:10 Average:94.30 Median:94.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 18:50:14 INFO - PID 4087 | Values: 94.0 95.0 94.0 94.0 94.0 94.0 95.0 95.0 94.0 94.0 18:50:14 INFO - PID 4087 | 18:50:14 INFO - PID 4087 | [#8] json-parse-financial Cycles:10 Average:56.60 Median:56.00 stddev:1.65 (2.9%) stddev-sans-first:1.73 18:50:14 INFO - PID 4087 | Values: 56.0 56.0 61.0 55.0 56.0 56.0 57.0 56.0 56.0 57.0 18:50:14 INFO - PID 4087 | 18:50:14 INFO - PID 4087 | [#9] json-stringify-tinderbox Cycles:10 Average:54.20 Median:54.00 stddev:0.42 (0.8%) stddev-sans-first:0.44 18:50:14 INFO - PID 4087 | Values: 54.0 54.0 54.0 54.0 54.0 55.0 55.0 54.0 54.0 54.0 18:50:14 INFO - PID 4087 | 18:50:14 INFO - PID 4087 | [#10] stanford-crypto-aes Cycles:10 Average:85.60 Median:82.00 stddev:7.71 (9.4%) stddev-sans-first:7.69 18:50:14 INFO - PID 4087 | Values: 93.0 102.0 80.0 82.0 88.0 79.0 79.0 82.0 91.0 80.0 18:50:14 INFO - PID 4087 | 18:50:14 INFO - PID 4087 | [#11] stanford-crypto-ccm Cycles:10 Average:134.30 Median:132.50 stddev:11.16 (8.4%) stddev-sans-first:11.82 18:50:14 INFO - PID 4087 | Values: 133.0 127.0 132.0 128.0 147.0 127.0 137.0 126.0 126.0 160.0 18:50:14 INFO - PID 4087 | 18:50:14 INFO - PID 4087 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.30 Median:182.50 stddev:5.33 (2.9%) stddev-sans-first:5.66 18:50:14 INFO - PID 4087 | Values: 183.0 180.0 181.0 191.0 180.0 185.0 182.0 177.0 194.0 180.0 18:50:14 INFO - PID 4087 | 18:50:14 INFO - PID 4087 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.00 Median:68.00 stddev:2.45 (3.6%) stddev-sans-first:1.81 18:50:14 INFO - PID 4087 | Values: 73.0 71.0 67.0 68.0 69.0 66.0 67.0 65.0 66.0 68.0 18:50:14 INFO - PID 4087 | -------- Summary: end -------- 18:50:14 INFO - PID 4087 | 18:50:14 INFO - PID 4087 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 18:50:14 INFO - TEST-INFO | 4087: exit 0 18:50:14 INFO - TEST-OK | kraken | took 61368ms 18:50:14 INFO - SUITE-END | took 1175s 18:50:15 INFO - Completed test suite (00:19:36) 18:50:15 INFO - javascript benchmark 18:50:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8347.110085586703, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3143.088504333742, 3166.6833316668335, 3186.187522181843, 3196.92902877536, 3207.951880721791, 6258.0, 6263.217097862767, 6266.498680105592, 6300.684965751706, 6316.873662526747, 6105.572609917308, 6194.814155575329, 6200.906986395196, 6201.720922558485, 6217.285012223596, 5658.122990936401, 5670.121131224667, 5682.0, 5688.516476099516, 5714.628549144313, 5684.2326285951485, 5729.541636669056, 5753.683547404891, 5786.450287222707, 5797.739101740411, 5669.518090962273, 5751.712414379264, 5753.0, 5764.7117644117625, 5802.506786923117, 5488.313960754906, 5616.073347897592, 5699.059655156894, 5735.713214339266, 5763.193152958589, 5132.897342053138, 5613.7193140342815, 5619.325680918275, 5667.4615911488345, 5699.8005069822775, 6093.451589356979, 6100.267967843887, 6127.662978536178, 6171.197744293258, 6227.252729672423, 6514.120593719835, 6612.86774264512, 6617.966910165466, 6633.933660663428, 6685.431738302202, 6643.103181070542, 6695.799126026227, 6697.0, 6704.865902681919, 6710.194776626788, 6161.106639537332, 6208.2239720034995, 6209.751609935643, 6220.937790622081, 6224.502039836802, 6951.582905025613, 6957.304269573002, 6964.477664175106, 6971.930280697229, 6979.4416446684145, 6510.218773747086, 6609.041688955074, 6617.602943823386, 6653.234877988996, 6674.16572928384, 6656.001599760075, 6669.199696036507, 6742.292059333798, 6752.75074111291, 6763.188417389846, 6606.8017959461295, 6626.635535045522, 6671.13275274221, 6722.226943901416, 6724.092247546617, 6565.376289252514, 6604.669766511655, 6610.504212184105, 6619.569727967667, 6640.435562977201, 5376.704281264568, 5397.784088636489, 5399.136138217867, 5400.0, 5401.7299135043095, 4647.256438969748, 4714.245720684743, 4726.503717109723, 4752.097101550694, 4755.976220118877, 5751.539876809845, 5839.795607153814, 5842.737076831553, 5846.1523079153285, 5846.152307915413, 6695.966520167465, 6698.263191048981, 6720.596764194066, 6799.694013769392, 6809.0, 5704.80033198836, 5736.368999410061, 5767.798127065533, 5795.0, 5799.2460980073165, 5818.301803783573, 5905.025670764362, 5915.438033386821, 5921.940780592225, 5938.049912014057, 6392.105105285264, 6488.837779055561, 6494.545381823227, 6496.447801936888, 6497.577657452251, 6641.970494573349, 6647.833804154935, 6744.022116793136, 6750.426213771884, 6774.187097548326, 6393.6483493408305, 6404.647744374104, 6474.805755827333, 6498.447631951337, 6581.703823327866, 6394.776182833579, 6522.543421960418, 6527.51043671736, 6530.314316996837, 6549.639769812516, 6627.768028119185, 6632.1709786276715, 6700.530962832555, 6705.228898676714, 6705.396514313735, 6449.419552240321, 6500.382463665944, 6528.640924749185, 6535.509836762357, 6553.2136143661155, 6432.742690292336, 6457.61254324742, 6540.607563546202, 6546.770863019771, 6548.148740663683, 6323.841903952438, 6370.522210834113, 6380.457661098859, 6397.3282805306635, 6456.54804163704, 6466.385693359123, 6518.674066296761, 6561.770338038145, 6570.704318305592, 6573.934260657332, 6312.9899216125195, 6335.0, 6336.017917222838, 6341.55609107358, 6370.0], "value": 6089.823126062027, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [749.7001199520191, 837.225566351125, 859.7635650196197, 860.7288704058217, 1123.2474242257697, 29355.119346419615, 29856.402871942548, 39137.60862391375, 40081.59918400815, 40093.398599021035, 39414.0, 39731.0, 39762.0, 39779.40330895039, 39802.40296395557, 7468.290512401327, 7621.504602200867, 7632.389408847307, 7642.121156067068, 7648.923510764905, 7391.445641576876, 7451.962740186292, 7556.546607203558, 7562.319391254786, 7565.0, 7542.585157816318, 7551.811204719871, 7561.508501947384, 7564.583947882864, 7588.468807183499, 7452.478326517146, 7552.207018263086, 7568.470207085506, 7576.128745194318, 7578.317951384347, 7446.553206807609, 7475.476716629838, 7482.850342993164, 7494.213107623676, 7503.212162722944, 35666.0, 35840.820795896114, 35856.462153067725, 35858.28283434343, 35913.64086359129, 3536.169000284931, 3543.787372757643, 3571.196480791827, 3573.10672331917, 3579.6420357964257, 29975.550366744515, 29997.850010749808, 30061.0, 30064.248393790112, 30278.697213027808, 4674.7428891410955, 4698.3187437821325, 4744.691595046311, 4767.499412561666, 4830.444498882638, 209453.0, 210757.0, 211953.9402302994, 212675.0, 213214.0, 46653.76673116613, 46898.296525551465, 46906.76546617245, 46956.29565556519, 46977.0, 48806.51193488091, 48880.26679599809, 51662.483375165764, 52514.21228681573, 52785.2082218767, 14860.18268995194, 14917.627059323386, 14995.32521036556, 15044.398224070914, 15078.0, 10328.845067323997, 12092.093093017882, 12125.333106679218, 12136.635900922987, 12141.75716485683, 12579.811302830465, 13615.251161186032, 13665.453381864614, 13997.0, 14003.089799163023, 5559.527440167631, 5718.885622287614, 5743.62666426681, 5827.484853937922, 5830.212921255661, 974.8099120671543, 976.4190306767462, 984.3454103021554, 1017.3539802225492, 1026.296986564191, 21689.566208675737, 21733.45666358322, 22260.55478890413, 22380.664290035664, 22742.65886011711, 1074.9731256718644, 1085.8154113800515, 1097.7749561339906, 1120.8935151160626, 1121.859767529059, 11589.594364197214, 11807.40962951849, 12093.0, 12182.208156469802, 12298.631041068782, 207812.0, 207986.0, 208206.0, 210255.0, 210533.0, 49438.50561494411, 49725.0, 49972.75013624909, 50151.0, 50271.49728502668, 1416.504223521759, 1423.736608727368, 1426.8216472940883, 1430.5064752660523, 1469.80157678712, 21274.468138296048, 22079.558408832054, 22112.33662990113, 22549.0, 25858.870705647103, 49047.264291034946, 49605.50394496009, 49627.25559116688, 49676.0, 49679.75160124176, 193938.0, 196270.0, 196771.0, 196791.0, 197169.0, 32480.35039299248, 33545.329093418484, 33650.83174584111, 33709.662903370656, 33974.0, 40574.18851622892, 41467.1706565873, 41644.791776040925, 41692.79153604334, 41913.16173676449, 28755.13734587966, 29580.55629165521, 29860.0, 30294.394112118076, 30389.84805076049, 27596.724032759415, 28062.579061314504, 29828.403431931674, 30310.242243944078, 30501.54247686331, 33895.32209355848, 33961.320773584885, 33993.83003084969, 34013.82993085019, 34021.0, 29019.41961160807, 29117.417651646425, 30430.54354184733, 30697.386052278383, 31211.687883121787, 21885.234016809336, 22886.42783930415, 22922.77077229273, 23102.537949241258, 23273.650895236246], "value": 16704.76013614867, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [563.1777604697141, 575.1660092865344, 580.747374891922, 584.880099579586, 595.982120536384, 21595.568088638258, 21725.565488690238, 22362.0, 22415.66376504352, 22572.54854902903, 25317.2404827855, 25887.870560647218, 25888.223353299385, 25968.610470842952, 25977.480450391027, 6419.197600299963, 6625.0393692914495, 6630.602163870183, 6655.567388119781, 6656.567323124005, 6121.540884433663, 6470.935290647104, 6570.605763654196, 6621.404073633372, 6633.4693224541925, 6401.295857455676, 6401.647909364983, 6405.29541750407, 6405.42351188393, 6427.678616069201, 6397.5521713480075, 6410.67946602668, 6425.100485931974, 6433.356664333576, 6434.742610295582, 5986.28164620244, 6007.429294217042, 6013.729382177812, 6016.2479690038745, 6019.759209631609, 26279.0, 28579.428411431654, 28780.568291475643, 29087.41825163506, 29271.0, 1856.6286742651523, 1918.0409795102448, 1919.9808001919941, 1920.5582715975265, 1922.826945574905, 18732.15705293265, 18915.24339026437, 19059.809401905943, 19103.426897193105, 19104.904475477535, 2281.5779080870093, 2305.561943230781, 2326.8371214015024, 2328.2316835444262, 2371.91698290559, 137833.0, 141149.0, 141431.0, 141828.58171418257, 142055.0, 13899.583012509438, 14461.638459038399, 14489.20309382973, 14807.70384592302, 15066.472673456596, 22378.888105559367, 22832.31503054911, 22905.541889162123, 22956.426089347566, 23014.309570712907, 9853.704388868346, 10013.299069065095, 10120.595176193017, 10125.949370253249, 10207.183425326104, 9001.864972025425, 9058.275337972946, 9084.40951338161, 9231.861522077174, 9246.214071803837, 9051.456912585267, 9185.448873067637, 9259.76850578741, 9394.765130871783, 9637.0, 2664.826786258926, 2672.425428532875, 2739.3836386813205, 2767.3911739417294, 2867.8709458074436, 961.2166084640995, 963.5113749257414, 966.3622009473713, 978.0806042320196, 985.8570507276405, 19437.708434373497, 19857.503562411057, 22682.546349072927, 24444.511109777704, 24702.752972470043, 1028.5114570578914, 1031.3141760729031, 1061.1192710050793, 1061.623123791043, 1068.8396740488834, 10759.677209683721, 11044.33733975964, 11222.719432014264, 11263.774724505627, 11330.674582946001, 500624.0, 506850.0, 511892.0, 513725.0, 518159.0, 116838.0, 117706.41146794212, 122795.85148475324, 123041.0, 123266.3836680811, 1241.74544218435, 1253.1290752926811, 1292.1665525735848, 1297.6366617347346, 1299.2853930338276, 19728.802711972698, 20632.0, 20669.379918602466, 21273.36179914605, 21538.030788614862, 48893.511064888895, 49480.25779613237, 56652.716736416056, 59110.704446477495, 60317.0, 65063.349366507624, 65217.0, 65475.34524654883, 65552.67223663851, 66076.33923660702, 14606.0, 14789.926050369679, 14985.0, 15018.774718379014, 15019.173945433105, 22982.540349193256, 23410.882945585163, 23462.765372346057, 23710.407239819142, 23724.28827135189, 18184.45446636603, 18312.26750929948, 18529.90735046316, 18570.628587427906, 18647.72028419602, 15465.0, 15967.281472333541, 16067.598310042342, 16281.267342969832, 16622.418215362406, 18565.907170464063, 18957.0, 19083.90458047701, 19114.330998415546, 19145.32991345352, 14706.0, 14774.0, 14783.113013219241, 14803.297620773317, 14857.851421486079, 17350.39273625417, 18398.540036499195, 18541.35105271309, 18649.347272845385, 18665.81334186695], "value": 12008.005659831315, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1327.7941919002556, 1366.2690460603578, 1378.951736689216, 1392.8369811207642, 1396.9650758731027, 13508.797368039475, 14106.153630782148, 14108.788368174486, 14130.788038179435, 14136.575902722909, 11893.345865977368, 12329.445174967126, 12376.566820161295, 13678.794818077737, 14269.571912842606, 2660.481206164794, 2736.315921019745, 2738.657667791526, 2739.4384151248946, 2740.5341092014405, 2630.06632645411, 2662.5340565401075, 2739.2740923655247, 2742.520058989669, 2744.8490333031677, 2745.135282386042, 2788.428372183708, 2789.302674331417, 2794.3433293176085, 2797.7202279772064, 2612.673415823022, 2652.6551548298694, 2659.7473240042164, 2661.2415461593355, 2712.2541301142246, 2650.3506640872915, 2654.1241390341143, 2672.291842661696, 2675.598660200966, 2683.409649877024, 13713.0, 13966.93016534921, 13986.580402587939, 14013.0, 14029.228392438514, 7455.589942553157, 7716.845663086763, 7743.303102720726, 7745.767626971144, 7769.572673502955, 5020.347354843883, 5116.590672746172, 5153.098207813618, 5246.422893481714, 5290.814821481268, 1825.9269629214816, 1833.4499650104915, 1839.420582516503, 1867.3184287735014, 1883.0772921268617, 22647.0, 22836.4290892729, 22838.657420138876, 22839.543209135725, 22855.542889142125, 6486.610803351814, 6526.347365263436, 6629.668516574248, 6664.5334826561675, 6752.155980502437, 15533.145676987875, 15543.378264869278, 15547.611309717122, 15549.689006219813, 15594.362591927911, 7453.180150183475, 7607.733729319558, 7611.010568626043, 7618.123915749649, 7630.2751238632245, 6971.58170509771, 6987.196472405637, 7065.89401158983, 7097.7160913562875, 7144.749933752294, 4896.363472748591, 5127.974405118991, 5139.254808052886, 5151.201563757623, 5196.92204616931, 1407.4440595964536, 1437.547172640615, 1464.9414023438942, 1472.7201831652167, 1480.25247250138, 830.7009476588423, 836.2139588786521, 853.7182729699184, 863.53369180643, 871.6252011634931, 7313.89029164563, 7470.551766893895, 7658.693652253847, 7700.422468314899, 7741.800020996755, 923.8752768376318, 945.8439357506072, 954.3081266082173, 966.4056605187759, 980.8528720691953, 4268.743875367488, 4857.611391088704, 4884.291777692286, 5000.449950505442, 5137.974310128426, 18474.0, 18739.34412295563, 19309.0, 19564.119614617375, 19575.314863979693, 18314.267429302945, 18819.52951176204, 19263.22947082101, 19383.127759250867, 19384.127714252892, 10525.052745252964, 10897.455127243606, 10955.780884382428, 10960.342379457257, 10982.670519884416, 7019.333163349474, 7427.145878224073, 7440.7023719052795, 7643.7324693635455, 7734.342580880463, 16409.753853691964, 16485.258163382437, 16486.505404837873, 16703.248353823863, 17693.20380582851, 16903.830961690226, 17204.13979301055, 17263.22315495831, 17361.913190433967, 17668.381606643707, 11187.440627968732, 11479.368634724837, 11560.6531804046, 11562.248453850809, 11570.19008669385, 12442.37788110609, 12538.561150359694, 12601.747965040831, 12683.936580317039, 12786.424610892345, 9422.387544809566, 11240.325580465198, 11265.493052812806, 11595.768084638428, 11709.180357375242, 10081.798364032826, 10082.596696132072, 10298.588056477956, 10347.741306467398, 10607.469626518488, 8864.911350886408, 9087.0, 9192.95403522978, 9240.491772952551, 9255.398399104037, 6554.279029306867, 6565.376289252514, 6735.461163106842, 6890.86300760389, 6984.126984126984, 6373.936260637334, 6547.476201903932, 6685.465162787063, 6807.217170025312, 6831.760888368884], "value": 6297.20808341528, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [494.10073665928024, 497.51989330296254, 521.6609204017388, 528.2182370092265, 533.0458479322014, 6045.183900173475, 6085.908711369333, 6246.3753624637575, 6247.281562620295, 6297.244330680313, 5985.221921150244, 5988.670623115727, 6030.66831324277, 6088.939110608892, 6102.725377358019, 4864.805407783684, 4909.7545122743895, 4917.50824917509, 4919.43426505951, 4958.429780575226, 4854.708717476945, 4892.3884514435695, 4923.55687988081, 4937.481564435737, 4988.950110498903, 4714.764261786914, 4841.2480327745925, 4846.515348465161, 4859.246816743411, 4913.606911447076, 4846.975765121187, 4895.388076490439, 4896.951030489703, 4949.985253023122, 4978.278149668314, 4413.823447062114, 4447.444069491314, 4450.554944505556, 4458.331250312447, 4482.238019536687, 6063.393660633901, 6104.344610094623, 6111.663858487781, 6114.847128821771, 6145.201123853871, 5721.9427805721825, 5730.710590117232, 5738.799142030009, 5749.367569567345, 5777.335606405275, 6504.479641628705, 6524.934750652527, 6531.4448271896945, 6605.504587155982, 6610.834729131762, 4836.975815120902, 4864.318995340655, 4868.269759536062, 4872.926906096412, 4879.731614761186, 6867.484938629623, 6937.549059311179, 6952.965235173791, 6984.3364880336285, 6994.230634630187, 4929.112759703288, 4950.232713929347, 4973.403191616957, 4987.077390682735, 4998.825041123543, 5033.6224783141415, 5057.949420505822, 5063.392392912874, 5071.163258062378, 5074.594032477393, 5689.890471358045, 5703.287089113861, 5711.771529138871, 5731.8219076750565, 5753.971230143822, 6555.4427873631275, 6557.3442655735, 6565.310642382577, 6576.040781648182, 6676.46588272937, 1654.288655877984, 1719.6732620802009, 1728.4382575662707, 1740.8945319722068, 1766.9469915902544, 1183.3432444992939, 1198.406788639629, 1202.22456515547, 1221.3587866370226, 1227.2881728597392, 5660.688662123623, 5705.400932902068, 5731.369549349568, 5744.253247077937, 5778.653280803165, 6503.366136714638, 6536.379043990813, 6542.443892269211, 6652.900206496906, 6676.833079172962, 5708.429157084342, 5779.624324418899, 5792.739326730307, 5793.333766616809, 5825.242718446575, 3521.7358698097746, 3543.511725075423, 3558.946615800765, 3566.6803282785536, 3577.767445116059, 6461.870762584816, 6485.156929599122, 6513.218413790375, 6526.510511711641, 6557.213134423804, 6851.2121106072445, 6883.208431030395, 6912.619805910723, 6917.481188910751, 6954.374106330454, 6411.6153030818305, 6439.806805795927, 6440.259370172396, 6481.282460148064, 6491.545591808528, 6578.750037492861, 6656.167979002625, 6661.666916654245, 6761.425278851353, 6794.3545363190415, 6647.102641143384, 6665.600063996176, 6671.199456065303, 6695.4978376620975, 6742.42689371409, 6399.360063993563, 6488.988413591802, 6491.709256955566, 6492.156019717407, 6563.1796025496815, 4928.211486162197, 4938.1112698429015, 4941.579965702956, 4948.307236986752, 4961.900761984668, 6643.900341494785, 6678.83302917431, 6699.363560461748, 6754.527183097332, 6776.966115169393, 6462.676866156579, 6506.633606942595, 6516.609003459807, 6558.639274839929, 6560.884649609482, 6371.164831649871, 6379.851626707237, 6455.289918109098, 6462.7414903405215, 6499.837504062436, 5879.412058794086, 6065.181200538047, 6313.305536391085, 6353.664326228767, 6391.296957334783, 6394.392532709385, 6403.51973601991, 6420.6147631142285, 6428.585711143541, 6559.7376104955265, 6280.371962803683, 6326.272478664829, 6355.618662880242, 6357.96821015892, 6378.0], "value": 5023.8743220085025, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [892.5135800988461, 897.80248345364, 917.9947956987105, 918.3066784577645, 927.6845872403386, 17868.82131178688, 18698.15858286377, 18718.438446846583, 18809.153588088517, 18927.526811829695, 19550.608987820237, 20784.792152078473, 20822.375328740123, 20827.375178744664, 20843.0, 1015.3653966271099, 1026.563710423071, 1026.8767747870265, 1033.1476531861208, 1034.8085604163216, 958.0611001218809, 1018.8777346718405, 1032.6592224565875, 1048.093399209682, 1055.0662663542782, 1083.3229231730168, 1084.3331351219022, 1084.614961688601, 1084.815581351172, 1087.141158484796, 1067.0983019348678, 1067.6209945469402, 1073.6725298783867, 1076.4725284610563, 1078.1698092468794, 787.4999375396607, 789.388224126301, 789.6525528767324, 792.8176519400513, 797.0634504457297, 18542.44372668822, 18708.812911870842, 18866.52833679169, 18868.15093320804, 19544.80455195444, 20304.69542956857, 20584.79415205859, 20717.482062948395, 21095.367138985854, 21120.471988200265, 15349.07905525672, 15575.0, 15664.765028524582, 15878.0, 15905.920470397574, 3411.880584179542, 3459.031471188046, 3463.497792820027, 3468.497067925137, 3481.425564781808, 115136.42431787787, 118313.0, 118893.0, 119891.99999999913, 123028.38485807514, 15557.29992150356, 15937.760933586005, 16226.107564084088, 16230.918845405933, 16244.431444899372, 29361.85319073434, 29430.852845736063, 29473.11580652584, 29518.0, 29636.0, 8054.838903221903, 8462.661493540312, 8496.49021058726, 8506.149385061568, 8598.914010859811, 4130.091379896418, 4202.390653355307, 4204.957950420457, 4211.4525111735275, 4218.325067989107, 4946.727929963887, 5154.278401023859, 5170.45710200431, 5178.637495375363, 5446.2647542582035, 1135.9091272698165, 1154.5439551377326, 1155.2375432214699, 1155.2779512804498, 1159.1885680024018, 627.645380360095, 652.4063102576632, 661.4807376209652, 674.1472037872096, 688.0985908459934, 10647.680569582924, 10724.195685323475, 11162.386068766133, 11361.261518001304, 11457.484413201426, 636.1729751323261, 642.2068745099798, 664.4750646988921, 670.5105273150627, 687.0278555843425, 5344.91982620261, 5444.646098003617, 5486.588505861996, 5496.093144631171, 5598.244237027949, 96283.0, 96931.51534242283, 99457.0, 103489.48255258675, 104496.0, 89951.10048899558, 91370.54314728384, 92784.0, 93246.0, 95576.04423955671, 73309.63345183346, 76497.0, 76532.0, 77903.61048194722, 78600.0, 10730.517126729472, 11271.154663400113, 11492.82760758606, 11886.881131188924, 11991.22057066288, 34544.136396590286, 35520.644793551735, 36112.47212695086, 36402.27195456129, 38694.80652596719, 45554.31668524908, 45964.54035459603, 46822.0, 47016.7649161752, 47493.76253118712, 16710.0, 16929.830701693318, 17355.47933561995, 17393.82606173922, 17437.04096274666, 19163.137658805666, 19442.319518816774, 19514.121864515848, 19691.901540492207, 19716.309929152412, 17275.481735547954, 17707.822921770618, 17842.0, 17851.73222401691, 17880.105994699952, 18041.09794510243, 18218.18018189158, 18313.0, 18332.908335458236, 18339.816601834344, 17107.401240956508, 17360.21879015422, 17443.040632765318, 17470.0, 17492.212850422016, 10991.835122473009, 11406.258593191416, 11545.769084618429, 11783.823242651195, 11938.343391113573, 11572.94213528927, 12152.513899443924, 12278.570250041206, 12358.50565977351, 12464.910708952793], "value": 8751.60877099546, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1541.4999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 107.0, 103.0, 107.0, 109.0, 110.0, 109.0, 103.0, 108.0, 112.0], "value": 106.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 116.0, 145.0, 182.0, 117.0, 146.0, 157.0, 121.0, 181.0, 120.0], "value": 143.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 167.0, 163.0, 164.0, 168.0, 164.0, 161.0, 166.0, 167.0, 167.0], "value": 165.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 78.0, 171.0, 76.0, 77.0, 76.0, 90.0, 112.0, 75.0], "value": 90.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 132.0, 80.0, 80.0, 83.0, 123.0, 80.0, 79.0, 123.0], "value": 93.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 113.0, 114.0, 115.0, 114.0, 114.0, 114.0, 114.0, 115.0, 113.0], "value": 114.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 151.0, 158.0, 151.0, 151.0, 150.0, 151.0, 151.0, 151.0, 151.0], "value": 151.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 94.0, 94.0, 94.0, 95.0, 95.0, 94.0, 94.0], "value": 94.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 61.0, 55.0, 56.0, 56.0, 57.0, 56.0, 56.0, 57.0], "value": 56.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 54.0, 54.0, 54.0, 55.0, 55.0, 54.0, 54.0, 54.0], "value": 54.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 102.0, 80.0, 82.0, 88.0, 79.0, 79.0, 82.0, 91.0, 80.0], "value": 85.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 127.0, 132.0, 128.0, 147.0, 127.0, 137.0, 126.0, 126.0, 160.0], "value": 134.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 180.0, 181.0, 191.0, 180.0, 185.0, 182.0, 177.0, 194.0, 180.0], "value": 183.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 71.0, 67.0, 68.0, 69.0, 66.0, 67.0, 65.0, 66.0, 68.0], "value": 68.0, "unit": "score"}]}]} 18:50:15 INFO - Return code: 0 18:50:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:50:15 INFO - # TBPL SUCCESS # 18:50:15 INFO - Running post-action listener: _package_coverage_data 18:50:15 INFO - Running post-action listener: _resource_record_post_action 18:50:15 INFO - [mozharness: 2017-08-01 01:50:15.445786Z] Finished run-tests step (success) 18:50:15 INFO - Running post-run listener: _resource_record_post_run 18:50:15 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 6041600; Write bytes: 444489728; Read time: 3316; Write time: 417032 18:50:15 INFO - TinderboxPrint: CPU usage
13.1% 18:50:15 INFO - TinderboxPrint: I/O read bytes / time
6,041,600 / 3,316 18:50:15 INFO - TinderboxPrint: I/O write bytes / time
444,489,728 / 417,032 18:50:15 INFO - TinderboxPrint: CPU idle
8,273.5 (86.8%) 18:50:15 INFO - TinderboxPrint: CPU user
1,202.3 (12.6%) 18:50:15 INFO - TinderboxPrint: Swap in / out
0 / 0 18:50:15 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 120213504; Read time: 0; Write time: 110720 18:50:15 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 18:50:15 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 3674112; Write bytes: 324276224; Read time: 2808; Write time: 306312 18:50:16 INFO - Running post-run listener: _upload_blobber_files 18:50:16 INFO - Blob upload gear active. 18:50:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:50:16 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 18:50:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:50:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:50:16 INFO - (blobuploader) - INFO - Open directory for files ... 18:50:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:50:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:50:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:50:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:50:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:50:17 INFO - (blobuploader) - INFO - Done attempting. 18:50:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:50:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:50:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:50:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:50:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:50:24 INFO - (blobuploader) - INFO - Done attempting. 18:50:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:50:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:50:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:50:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:50:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:50:24 INFO - (blobuploader) - INFO - Done attempting. 18:50:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:50:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:50:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:50:25 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:50:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:50:25 INFO - (blobuploader) - INFO - Done attempting. 18:50:25 INFO - (blobuploader) - INFO - Iteration through files over. 18:50:25 INFO - Return code: 0 18:50:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:50:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:50:25 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/cd48988ba572613efd411f1628a3074945bd21c60d1563bfe916c4b7cdf6310153b241f163d11d9577f69d1db8210ca790c97150acd7b0e2380f0071a812b5e2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b81077d86231c81144eb508956e1df82433ba582f308ecfa65879cde35564b1b9a47f7cfdc51dca43e42803b1f689c68becf1342989663248f1b4c4573dd0ce7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1b34ebc4bf2d15a697d7d48f93698ec01e069cd38589e7180f4b97e7d1724982d17fdae3c86817cff00734ad10296f48e193576a4891500d49e9b2877e7840a5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/458852e89b2de95fc86a06938187f655510902cab7971e1ba706b61195406ecc7fe9d9dcb4f958543bc2c347572377b168fbdf496a1d9647431581c011105d0d"} 18:50:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:50:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:50:25 INFO - Contents: 18:50:25 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/cd48988ba572613efd411f1628a3074945bd21c60d1563bfe916c4b7cdf6310153b241f163d11d9577f69d1db8210ca790c97150acd7b0e2380f0071a812b5e2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b81077d86231c81144eb508956e1df82433ba582f308ecfa65879cde35564b1b9a47f7cfdc51dca43e42803b1f689c68becf1342989663248f1b4c4573dd0ce7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1b34ebc4bf2d15a697d7d48f93698ec01e069cd38589e7180f4b97e7d1724982d17fdae3c86817cff00734ad10296f48e193576a4891500d49e9b2877e7840a5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/458852e89b2de95fc86a06938187f655510902cab7971e1ba706b61195406ecc7fe9d9dcb4f958543bc2c347572377b168fbdf496a1d9647431581c011105d0d"} 18:50:25 INFO - Running post-run listener: copy_logs_to_upload_dir 18:50:25 INFO - Copying logs to upload dir... 18:50:25 INFO - mkdir: /builds/slave/test/build/upload/logs 18:50:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.123146 ========= master_lag: -0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2017-07-31 18:50:25.790763) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-31 18:50:25.794316) ========= 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=3a3620fb28aea1604ab609ef000001d2-1501550826.510254-1621757349 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/cd48988ba572613efd411f1628a3074945bd21c60d1563bfe916c4b7cdf6310153b241f163d11d9577f69d1db8210ca790c97150acd7b0e2380f0071a812b5e2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b81077d86231c81144eb508956e1df82433ba582f308ecfa65879cde35564b1b9a47f7cfdc51dca43e42803b1f689c68becf1342989663248f1b4c4573dd0ce7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1b34ebc4bf2d15a697d7d48f93698ec01e069cd38589e7180f4b97e7d1724982d17fdae3c86817cff00734ad10296f48e193576a4891500d49e9b2877e7840a5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/458852e89b2de95fc86a06938187f655510902cab7971e1ba706b61195406ecc7fe9d9dcb4f958543bc2c347572377b168fbdf496a1d9647431581c011105d0d"} build_url:https://queue.taskcluster.net/v1/task/ZbeB3PV6RPy_XbcR57i83g/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011178 build_url: 'https://queue.taskcluster.net/v1/task/ZbeB3PV6RPy_XbcR57i83g/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/cd48988ba572613efd411f1628a3074945bd21c60d1563bfe916c4b7cdf6310153b241f163d11d9577f69d1db8210ca790c97150acd7b0e2380f0071a812b5e2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b81077d86231c81144eb508956e1df82433ba582f308ecfa65879cde35564b1b9a47f7cfdc51dca43e42803b1f689c68becf1342989663248f1b4c4573dd0ce7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1b34ebc4bf2d15a697d7d48f93698ec01e069cd38589e7180f4b97e7d1724982d17fdae3c86817cff00734ad10296f48e193576a4891500d49e9b2877e7840a5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/458852e89b2de95fc86a06938187f655510902cab7971e1ba706b61195406ecc7fe9d9dcb4f958543bc2c347572377b168fbdf496a1d9647431581c011105d0d"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-31 18:50:25.836881) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-31 18:50:25.837178) ========= 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=3a3620fb28aea1604ab609ef000001d2-1501550826.510254-1621757349 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004470 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-31 18:50:25.917537) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-31 18:50:25.917825) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-31 18:50:25.918110) ========= ========= Total master_lag: 0.16 =========