builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-062 starttime: 1502759992.76 results: success (0) revision: 92f3de33d97f55d54b2baad585b87e76aaa5ec58 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-14 18:19:52.763852) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-14 18:19:52.764469) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-14 18:19:52.764828) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-14 18:19:52.820758) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-14 18:19:52.821052) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1502759692.568319-793261140 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-14 18:19:54-- 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% 110M=0s 2017-08-14 18:19:54 (110 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.141108 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-14 18:19:52.977558) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-14 18:19:52.980976) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1502759692.568319-793261140 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019293 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-14 18:19:53.035537) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 13 secs) (at 2017-08-14 18:19:53.038932) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 92f3de33d97f55d54b2baad585b87e76aaa5ec58 --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 92f3de33d97f55d54b2baad585b87e76aaa5ec58 --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=a4ce256d2ec601f24a60a71e000001bc-1502759692.568319-793261140 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-14 18:19:55,062 truncating revision to first 12 chars 2017-08-14 18:19:55,062 Setting DEBUG logging. 2017-08-14 18:19:55,062 attempt 1/10 2017-08-14 18:19:55,062 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/92f3de33d97f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-14 18:19:55,563 attempt 1/10 2017-08-14 18:19:56,615 current task status: no status available at this point. state: STARTED 2017-08-14 18:19:56,616 sleeping for 11.00s (attempt 1/10) 2017-08-14 18:20:07,627 attempt 2/10 2017-08-14 18:20:08,000 current task status: Task completed! Check 's3_urls' for upload locations. state: SUCCESS 2017-08-14 18:20:08,212 unpacking tar archive at: mozilla-central-92f3de33d97f/testing/mozharness/ program finished with exit code 0 elapsedTime=13.445473 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 13 secs) (at 2017-08-14 18:20:06.498750) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-14 18:20:06.499073) ========= script_repo_revision: 92f3de33d97f55d54b2baad585b87e76aaa5ec58 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-14 18:20:06.499487) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-14 18:20:06.499814) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-14 18:20:06.510360) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2017-08-14 18:20:06.510689) ========= /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=a4ce256d2ec601f24a60a71e000001bc-1502759692.568319-793261140 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:20:08 INFO - MultiFileLogger online at 20170814 18:20:08 in /builds/slave/test 18:20:08 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:20:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:20:08 INFO - {'append_to_log': False, 18:20:08 INFO - 'base_work_dir': '/builds/slave/test', 18:20:08 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 18:20:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:20:08 INFO - 'branch': 'Firefox-Non-PGO', 18:20:08 INFO - 'buildbot_json_path': 'buildprops.json', 18:20:08 INFO - 'code_coverage': False, 18:20:08 INFO - 'config_files': ('talos/linux_config.py',), 18:20:08 INFO - 'default_actions': ('clobber', 18:20:08 INFO - 'read-buildbot-config', 18:20:08 INFO - 'download-and-extract', 18:20:08 INFO - 'populate-webroot', 18:20:08 INFO - 'create-virtualenv', 18:20:08 INFO - 'install', 18:20:08 INFO - 'setup-mitmproxy', 18:20:08 INFO - 'run-tests'), 18:20:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:20:08 INFO - 'disable_ccov_upload': False, 18:20:08 INFO - 'download_minidump_stackwalk': True, 18:20:08 INFO - 'download_symbols': 'ondemand', 18:20:08 INFO - 'enable_stylo': False, 18:20:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:20:08 INFO - 'tooltool.py': '/tools/tooltool.py', 18:20:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:20:08 INFO - '/tools/misc-python/virtualenv.py')}, 18:20:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:20:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:20:08 INFO - 'gecko_profile': False, 18:20:08 INFO - 'gecko_profile_interval': 0, 18:20:08 INFO - 'installer_path': 'installer.exe', 18:20:08 INFO - 'log_level': 'info', 18:20:08 INFO - 'log_name': 'talos', 18:20:08 INFO - 'log_to_console': True, 18:20:08 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:20:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:20:08 INFO - 'opt_config_files': (), 18:20:08 INFO - 'pip_index': False, 18:20:08 INFO - 'suite': 'dromaeojs-e10s', 18:20:08 INFO - 'system_bits': '32', 18:20:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:20:08 INFO - 'title': 'talos-linux64-ix-062', 18:20:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:20:08 INFO - 'use_talos_json': True, 18:20:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:20:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:20:08 INFO - 'work_dir': 'build'} 18:20:08 INFO - [mozharness: 2017-08-15 01:20:08.646261Z] Running clobber step. 18:20:08 INFO - Running pre-action listener: _resource_record_pre_action 18:20:08 INFO - Running main action method: clobber 18:20:08 INFO - rmtree: /builds/slave/test/build 18:20:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:20:09 INFO - Running post-action listener: _resource_record_post_action 18:20:09 INFO - [mozharness: 2017-08-15 01:20:09.684172Z] Finished clobber step (success) 18:20:09 INFO - [mozharness: 2017-08-15 01:20:09.684273Z] Running read-buildbot-config step. 18:20:09 INFO - Running pre-action listener: _resource_record_pre_action 18:20:09 INFO - Running main action method: read_buildbot_config 18:20:09 INFO - Using buildbot properties: 18:20:09 INFO - { 18:20:09 INFO - "project": "", 18:20:09 INFO - "product": "firefox", 18:20:09 INFO - "who": "kwierso@gmail.com", 18:20:09 INFO - "installer_path": "public/build/target.tar.bz2", 18:20:09 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 18:20:09 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 18:20:09 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 18:20:09 INFO - "stage_platform": "linux64", 18:20:09 INFO - "basedir": "/builds/slave/test", 18:20:09 INFO - "buildnumber": 1077, 18:20:09 INFO - "platform": "ubuntu64_hw", 18:20:09 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 18:20:09 INFO - "slavebuilddir": "test", 18:20:09 INFO - "taskId": "fq-IuX4SQj2SriVimWBPag", 18:20:09 INFO - "branch": "mozilla-central", 18:20:09 INFO - "script_repo_revision": "production", 18:20:09 INFO - "revision": "92f3de33d97f55d54b2baad585b87e76aaa5ec58", 18:20:09 INFO - "slavename": "talos-linux64-ix-062", 18:20:09 INFO - "repo_path": "mozilla-central" 18:20:09 INFO - } 18:20:09 INFO - Finding installer, test and symbols from parent task. 18:20:09 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fq-IuX4SQj2SriVimWBPag'}, attempt #1 18:20:10 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fq-IuX4SQj2SriVimWBPag'}, attempt #1 18:20:10 INFO - Task dependencies: QxuElLloREiYtzE68JGu4g 18:20:10 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QxuElLloREiYtzE68JGu4g'}, attempt #1 18:20:11 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/QxuElLloREiYtzE68JGu4g/artifacts/public/build/target.tar.bz2 18:20:11 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/QxuElLloREiYtzE68JGu4g/artifacts/public/build/target.test_packages.json 18:20:11 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/QxuElLloREiYtzE68JGu4g/artifacts/public/build/target.crashreporter-symbols.zip 18:20:11 INFO - Running post-action listener: _resource_record_post_action 18:20:11 INFO - [mozharness: 2017-08-15 01:20:11.012167Z] Finished read-buildbot-config step (success) 18:20:11 INFO - [mozharness: 2017-08-15 01:20:11.012386Z] Running download-and-extract step. 18:20:11 INFO - Running pre-action listener: _resource_record_pre_action 18:20:11 INFO - Running main action method: download_and_extract 18:20:11 INFO - mkdir: /builds/slave/test/build/tests 18:20: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:20:11 INFO - trying https://queue.taskcluster.net/v1/task/QxuElLloREiYtzE68JGu4g/artifacts/public/build/target.test_packages.json 18:20:11 INFO - Downloading https://queue.taskcluster.net/v1/task/QxuElLloREiYtzE68JGu4g/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 18:20:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QxuElLloREiYtzE68JGu4g/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 18:20:11 INFO - Downloaded 989 bytes. 18:20:11 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 18:20:11 INFO - Using the following test package requirements: 18:20:11 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 18:20:11 INFO - u'common': [u'target.common.tests.zip'], 18:20:11 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 18:20:11 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 18:20:11 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 18:20:11 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 18:20:11 INFO - u'mozbase': [u'target.common.tests.zip'], 18:20:11 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 18:20:11 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 18:20:11 INFO - u'web-platform': [u'target.common.tests.zip', 18:20:11 INFO - u'target.web-platform.tests.tar.gz'], 18:20:11 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 18:20:11 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 18:20:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QxuElLloREiYtzE68JGu4g/artifacts/public/build/target.common.tests.zip 18:20:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QxuElLloREiYtzE68JGu4g/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:20:11 INFO - Fetch https://queue.taskcluster.net/v1/task/QxuElLloREiYtzE68JGu4g/artifacts/public/build/target.common.tests.zip into memory 18:20:17 INFO - Content-Length response header: 54034585 18:20:17 INFO - Bytes received: 54034585 18:20:27 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 18:20:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QxuElLloREiYtzE68JGu4g/artifacts/public/build/target.common.tests.zip 18:20:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QxuElLloREiYtzE68JGu4g/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:20:27 INFO - Fetch https://queue.taskcluster.net/v1/task/QxuElLloREiYtzE68JGu4g/artifacts/public/build/target.common.tests.zip into memory 18:20:32 INFO - Content-Length response header: 54034585 18:20:32 INFO - Bytes received: 54034585 18:20:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QxuElLloREiYtzE68JGu4g/artifacts/public/build/target.talos.tests.zip 18:20:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QxuElLloREiYtzE68JGu4g/artifacts/public/build/target.talos.tests.zip'}, attempt #1 18:20:44 INFO - Fetch https://queue.taskcluster.net/v1/task/QxuElLloREiYtzE68JGu4g/artifacts/public/build/target.talos.tests.zip into memory 18:20:45 INFO - Content-Length response header: 14496050 18:20:45 INFO - Bytes received: 14496050 18:20:45 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:20:45 INFO - trying https://queue.taskcluster.net/v1/task/QxuElLloREiYtzE68JGu4g/artifacts/public/build/target.tar.bz2 18:20:45 INFO - Downloading https://queue.taskcluster.net/v1/task/QxuElLloREiYtzE68JGu4g/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 18:20:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QxuElLloREiYtzE68JGu4g/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 18:20:50 INFO - Downloaded 61778025 bytes. 18:20:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QxuElLloREiYtzE68JGu4g/artifacts/public/build/target.tar.bz2 18:20:50 INFO - mkdir: /builds/slave/test/properties 18:20:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:20:50 INFO - Writing to file /builds/slave/test/properties/build_url 18:20:50 INFO - Contents: 18:20:50 INFO - build_url:https://queue.taskcluster.net/v1/task/QxuElLloREiYtzE68JGu4g/artifacts/public/build/target.tar.bz2 18:20:50 INFO - Running post-action listener: _resource_record_post_action 18:20:50 INFO - Running post-action listener: set_extra_try_arguments 18:20:50 INFO - [mozharness: 2017-08-15 01:20:50.728753Z] Finished download-and-extract step (success) 18:20:50 INFO - [mozharness: 2017-08-15 01:20:50.728981Z] Running populate-webroot step. 18:20:50 INFO - Running pre-action listener: _resource_record_pre_action 18:20:50 INFO - Running main action method: populate_webroot 18:20:50 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:20:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:20:50 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:20:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:20:50 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:20:50 INFO - 'tests': ['damp', 'tps']}, 18:20:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:20:50 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:20:50 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:20:50 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:20:50 INFO - 'other-e10s': {'tests': ['a11yr', 18:20:50 INFO - 'ts_paint', 18:20:50 INFO - 'tpaint', 18:20:50 INFO - 'sessionrestore', 18:20:50 INFO - 'sessionrestore_many_windows', 18:20:50 INFO - 'sessionrestore_no_auto_restore', 18:20:50 INFO - 'tabpaint']}, 18:20:50 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 18:20:50 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 18:20:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:20:50 INFO - 'tsvgr_opacity', 18:20:50 INFO - 'tart', 18:20:50 INFO - 'tscrollx', 18:20:50 INFO - 'tsvg_static']}, 18:20:50 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:20:50 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:20:50 INFO - 'talos_options': ['--mitmproxy', 18:20:50 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:20:50 INFO - '--firstNonBlankPaint'], 18:20:50 INFO - 'tests': ['tp6_google', 18:20:50 INFO - 'tp6_youtube', 18:20:50 INFO - 'tp6_amazon', 18:20:50 INFO - 'tp6_facebook']}, 18:20:50 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:20:50 INFO - 'talos_options': ['--stylo', 18:20:50 INFO - '--mitmproxy', 18:20:50 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:20:50 INFO - '--firstNonBlankPaint'], 18:20:50 INFO - 'tests': ['tp6_google', 18:20:50 INFO - 'tp6_youtube', 18:20:50 INFO - 'tp6_amazon', 18:20:50 INFO - 'tp6_facebook']}, 18:20:50 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:20:50 INFO - 'talos_options': ['--stylo', 18:20:50 INFO - '--stylo-threads=1', 18:20:50 INFO - '--mitmproxy', 18:20:50 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:20:50 INFO - '--firstNonBlankPaint'], 18:20:50 INFO - 'tests': ['tp6_google', 18:20:50 INFO - 'tp6_youtube', 18:20:50 INFO - 'tp6_amazon', 18:20:50 INFO - 'tp6_facebook']}, 18:20:50 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:20:50 INFO - 'talos_options': ['--xperf_path', 18:20:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:20:50 INFO - 'tests': ['tp5n']}}} 18:20:50 INFO - Running post-action listener: _resource_record_post_action 18:20:50 INFO - [mozharness: 2017-08-15 01:20:50.737602Z] Finished populate-webroot step (success) 18:20:50 INFO - [mozharness: 2017-08-15 01:20:50.737692Z] Running create-virtualenv step. 18:20:50 INFO - Running pre-action listener: _resource_record_pre_action 18:20:50 INFO - Running main action method: create_virtualenv 18:20:50 INFO - Creating virtualenv /builds/slave/test/build/venv 18:20:50 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:20:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:20:50 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 18:20:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:20:50 INFO - Using real prefix '/usr' 18:20:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:20:51 INFO - Installing distribute.............................................................................................................................................................................................done. 18:20:53 INFO - Installing pip.................done. 18:20:53 INFO - Return code: 0 18:20:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:20:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:20:53 INFO - Reading from file tmpfile_stdout 18:20:53 INFO - Output received: 18:20:53 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:20:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:20:53 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:20:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:53 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:20:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:53 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 0x1bf61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f64b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f67f00>, '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 0x1f62ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f63050>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1502759692.568319-793261140', '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:20:53 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:20:53 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:20:53 INFO - Using env: {'DISPLAY': ':0', 18:20:53 INFO - 'HOME': '/home/cltbld', 18:20:53 INFO - 'LANG': 'en_US.UTF-8', 18:20:53 INFO - 'LANGUAGE': 'en_US:en', 18:20:53 INFO - 'LOGNAME': 'cltbld', 18:20:53 INFO - 'MAIL': '/var/mail/cltbld', 18:20:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:53 INFO - 'MOZ_NO_REMOTE': '1', 18:20:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:20:53 INFO - 'NO_EM_RESTART': '1', 18:20:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:20:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:53 INFO - 'PWD': '/builds/slave/test', 18:20:53 INFO - 'SHELL': '/bin/bash', 18:20:53 INFO - 'SHLVL': '1', 18:20:53 INFO - 'TERM': 'linux', 18:20:53 INFO - 'TMOUT': '86400', 18:20:53 INFO - 'USER': 'cltbld', 18:20:53 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1502759692.568319-793261140', 18:20:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:53 INFO - '_': '/tools/buildbot/bin/python'} 18:20:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:53 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:20:53 INFO - Cleaning up... 18:20:53 INFO - Return code: 0 18:20:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:20:53 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:20:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:53 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:20:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:53 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 0x1bf61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f64b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f67f00>, '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 0x1f62ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f63050>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1502759692.568319-793261140', '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:20:53 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:20:53 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:20:53 INFO - Using env: {'DISPLAY': ':0', 18:20:53 INFO - 'HOME': '/home/cltbld', 18:20:53 INFO - 'LANG': 'en_US.UTF-8', 18:20:53 INFO - 'LANGUAGE': 'en_US:en', 18:20:53 INFO - 'LOGNAME': 'cltbld', 18:20:53 INFO - 'MAIL': '/var/mail/cltbld', 18:20:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:53 INFO - 'MOZ_NO_REMOTE': '1', 18:20:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:20:53 INFO - 'NO_EM_RESTART': '1', 18:20:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:20:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:53 INFO - 'PWD': '/builds/slave/test', 18:20:53 INFO - 'SHELL': '/bin/bash', 18:20:53 INFO - 'SHLVL': '1', 18:20:53 INFO - 'TERM': 'linux', 18:20:53 INFO - 'TMOUT': '86400', 18:20:53 INFO - 'USER': 'cltbld', 18:20:53 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1502759692.568319-793261140', 18:20:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:53 INFO - '_': '/tools/buildbot/bin/python'} 18:20:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:53 INFO - Downloading/unpacking psutil>=3.1.1 18:20:53 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:20:53 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:20:53 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:20:53 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:20:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:20:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:20:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:20:55 INFO - Installing collected packages: psutil 18:20:55 INFO - Running setup.py install for psutil 18:20:55 INFO - building 'psutil._psutil_linux' extension 18:20:55 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:20:55 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:20:55 INFO - building 'psutil._psutil_posix' extension 18:20:55 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:20:55 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:20:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:20:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:20:55 INFO - Successfully installed psutil 18:20:55 INFO - Cleaning up... 18:20:55 INFO - Return code: 0 18:20:55 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:20: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:20:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20: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:20:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:55 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 0x1bf61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f64b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f67f00>, '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 0x1f62ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f63050>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1502759692.568319-793261140', '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:20:55 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:20:55 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:20:55 INFO - Using env: {'DISPLAY': ':0', 18:20:55 INFO - 'HOME': '/home/cltbld', 18:20:55 INFO - 'LANG': 'en_US.UTF-8', 18:20:55 INFO - 'LANGUAGE': 'en_US:en', 18:20:55 INFO - 'LOGNAME': 'cltbld', 18:20:55 INFO - 'MAIL': '/var/mail/cltbld', 18:20:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:55 INFO - 'MOZ_NO_REMOTE': '1', 18:20:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:20:55 INFO - 'NO_EM_RESTART': '1', 18:20:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:20:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:55 INFO - 'PWD': '/builds/slave/test', 18:20:55 INFO - 'SHELL': '/bin/bash', 18:20:55 INFO - 'SHLVL': '1', 18:20:55 INFO - 'TERM': 'linux', 18:20:55 INFO - 'TMOUT': '86400', 18:20:55 INFO - 'USER': 'cltbld', 18:20:55 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1502759692.568319-793261140', 18:20:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:55 INFO - '_': '/tools/buildbot/bin/python'} 18:20:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:55 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:20:55 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:20:55 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:20:55 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:20:55 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:20:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:20:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:20:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:20:57 INFO - Installing collected packages: mozsystemmonitor 18:20:57 INFO - Running setup.py install for mozsystemmonitor 18:20:57 INFO - Successfully installed mozsystemmonitor 18:20:57 INFO - Cleaning up... 18:20:57 INFO - Return code: 0 18:20:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:20:57 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:20:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:57 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:20:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:57 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 0x1bf61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f64b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f67f00>, '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 0x1f62ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f63050>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1502759692.568319-793261140', '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:20:57 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:20:57 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:20:57 INFO - Using env: {'DISPLAY': ':0', 18:20:57 INFO - 'HOME': '/home/cltbld', 18:20:57 INFO - 'LANG': 'en_US.UTF-8', 18:20:57 INFO - 'LANGUAGE': 'en_US:en', 18:20:57 INFO - 'LOGNAME': 'cltbld', 18:20:57 INFO - 'MAIL': '/var/mail/cltbld', 18:20:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:57 INFO - 'MOZ_NO_REMOTE': '1', 18:20:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:20:57 INFO - 'NO_EM_RESTART': '1', 18:20:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:20:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:57 INFO - 'PWD': '/builds/slave/test', 18:20:57 INFO - 'SHELL': '/bin/bash', 18:20:57 INFO - 'SHLVL': '1', 18:20:57 INFO - 'TERM': 'linux', 18:20:57 INFO - 'TMOUT': '86400', 18:20:57 INFO - 'USER': 'cltbld', 18:20:57 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1502759692.568319-793261140', 18:20:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:57 INFO - '_': '/tools/buildbot/bin/python'} 18:20:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:57 INFO - Downloading/unpacking jsonschema==2.5.1 18:20:57 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:20:57 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:20:57 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:20:57 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:20:58 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:20:58 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:20:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:58 INFO - Downloading functools32-3.2.3-2.tar.gz 18:20:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:20:58 INFO - warning: no files found matching '*.txt' 18:20:58 INFO - no previously-included directories found matching 'build' 18:20:58 INFO - no previously-included directories found matching 'dist' 18:20:58 INFO - no previously-included directories found matching '.git*' 18:20:58 INFO - Installing collected packages: jsonschema, functools32 18:20:58 INFO - Running setup.py install for functools32 18:20:59 INFO - warning: no files found matching '*.txt' 18:20:59 INFO - no previously-included directories found matching 'build' 18:20:59 INFO - no previously-included directories found matching 'dist' 18:20:59 INFO - no previously-included directories found matching '.git*' 18:20:59 INFO - Successfully installed jsonschema functools32 18:20:59 INFO - Cleaning up... 18:20:59 INFO - Return code: 0 18:20:59 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:20:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bf61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f64b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f67f00>, '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 0x1f62ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f63050>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1502759692.568319-793261140', '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:20:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:20:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:20:59 INFO - Using env: {'DISPLAY': ':0', 18:20:59 INFO - 'HOME': '/home/cltbld', 18:20:59 INFO - 'LANG': 'en_US.UTF-8', 18:20:59 INFO - 'LANGUAGE': 'en_US:en', 18:20:59 INFO - 'LOGNAME': 'cltbld', 18:20:59 INFO - 'MAIL': '/var/mail/cltbld', 18:20:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:59 INFO - 'MOZ_NO_REMOTE': '1', 18:20:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:20:59 INFO - 'NO_EM_RESTART': '1', 18:20:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:20:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:59 INFO - 'PWD': '/builds/slave/test', 18:20:59 INFO - 'SHELL': '/bin/bash', 18:20:59 INFO - 'SHLVL': '1', 18:20:59 INFO - 'TERM': 'linux', 18:20:59 INFO - 'TMOUT': '86400', 18:20:59 INFO - 'USER': 'cltbld', 18:20:59 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1502759692.568319-793261140', 18:20:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:59 INFO - '_': '/tools/buildbot/bin/python'} 18:20:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:59 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:20:59 INFO - Cleaning up... 18:20:59 INFO - Return code: 0 18:20:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:20:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bf61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f64b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f67f00>, '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 0x1f62ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f63050>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1502759692.568319-793261140', '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:20:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:20:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:20:59 INFO - Using env: {'DISPLAY': ':0', 18:20:59 INFO - 'HOME': '/home/cltbld', 18:20:59 INFO - 'LANG': 'en_US.UTF-8', 18:20:59 INFO - 'LANGUAGE': 'en_US:en', 18:20:59 INFO - 'LOGNAME': 'cltbld', 18:20:59 INFO - 'MAIL': '/var/mail/cltbld', 18:20:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:59 INFO - 'MOZ_NO_REMOTE': '1', 18:20:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:20:59 INFO - 'NO_EM_RESTART': '1', 18:20:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:20:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:59 INFO - 'PWD': '/builds/slave/test', 18:20:59 INFO - 'SHELL': '/bin/bash', 18:20:59 INFO - 'SHLVL': '1', 18:20:59 INFO - 'TERM': 'linux', 18:20:59 INFO - 'TMOUT': '86400', 18:20:59 INFO - 'USER': 'cltbld', 18:20:59 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1502759692.568319-793261140', 18:20:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:59 INFO - '_': '/tools/buildbot/bin/python'} 18:20:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:59 INFO - Downloading/unpacking blobuploader==1.2.4 18:20:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:21:00 INFO - Downloading blobuploader-1.2.4.tar.gz 18:21:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:21:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:21:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:21:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:21:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:21:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:21:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:21:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:21:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:21:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:21:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:21:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:21:01 INFO - Downloading docopt-0.6.1.tar.gz 18:21:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:21:01 INFO - Installing collected packages: blobuploader, requests, docopt 18:21:01 INFO - Running setup.py install for blobuploader 18:21:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:21:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:21:01 INFO - Running setup.py install for requests 18:21:01 INFO - Running setup.py install for docopt 18:21:01 INFO - Successfully installed blobuploader requests docopt 18:21:01 INFO - Cleaning up... 18:21:01 INFO - Return code: 0 18:21:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:21:01 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:21:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:21:01 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:21:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:21:01 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 0x1bf61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f64b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f67f00>, '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 0x1f62ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f63050>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1502759692.568319-793261140', '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:21:01 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:21:01 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:21:01 INFO - Using env: {'DISPLAY': ':0', 18:21:01 INFO - 'HOME': '/home/cltbld', 18:21:01 INFO - 'LANG': 'en_US.UTF-8', 18:21:01 INFO - 'LANGUAGE': 'en_US:en', 18:21:01 INFO - 'LOGNAME': 'cltbld', 18:21:01 INFO - 'MAIL': '/var/mail/cltbld', 18:21:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:21:01 INFO - 'MOZ_NO_REMOTE': '1', 18:21:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:21:01 INFO - 'NO_EM_RESTART': '1', 18:21:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:21:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:21:01 INFO - 'PWD': '/builds/slave/test', 18:21:01 INFO - 'SHELL': '/bin/bash', 18:21:01 INFO - 'SHLVL': '1', 18:21:01 INFO - 'TERM': 'linux', 18:21:01 INFO - 'TMOUT': '86400', 18:21:01 INFO - 'USER': 'cltbld', 18:21:01 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1502759692.568319-793261140', 18:21:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:21:01 INFO - '_': '/tools/buildbot/bin/python'} 18:21:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:21:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:21:02 INFO - Running setup.py (path:/tmp/pip-F37Sn2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:21:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:21:02 INFO - Running setup.py (path:/tmp/pip-46ws7R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:21:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:21:02 INFO - Running setup.py (path:/tmp/pip-_qjqeF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:21:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:21:02 INFO - Running setup.py (path:/tmp/pip-Scvp3w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:21:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:21:02 INFO - Running setup.py (path:/tmp/pip-46PBIA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:21:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:21:02 INFO - Running setup.py (path:/tmp/pip-0i5xUA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:21:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:21:02 INFO - Running setup.py (path:/tmp/pip-UaE2vy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:21:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:21:02 INFO - Running setup.py (path:/tmp/pip-wfgnjm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:21:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:21:02 INFO - Running setup.py (path:/tmp/pip-saWNMu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:21:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:21:02 INFO - Running setup.py (path:/tmp/pip-xy6aHp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:21:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:21:02 INFO - Running setup.py (path:/tmp/pip-DpuinY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:21:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:21:02 INFO - Running setup.py (path:/tmp/pip-apOJra-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:21:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:21:02 INFO - Running setup.py (path:/tmp/pip-Nokiwy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:21:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:21:03 INFO - Running setup.py (path:/tmp/pip-kNbAzE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:21:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:21:03 INFO - Running setup.py (path:/tmp/pip-Z9QJGV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:21:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:21:03 INFO - Running setup.py (path:/tmp/pip-NmWYDB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:21:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:21:03 INFO - Running setup.py (path:/tmp/pip-ykSIlt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:21:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:21:03 INFO - Running setup.py install for manifestparser 18:21:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:21:03 INFO - Running setup.py install for mozcrash 18:21:03 INFO - Running setup.py install for mozdebug 18:21:03 INFO - Running setup.py install for mozdevice 18:21:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:21:03 INFO - Running setup.py install for mozfile 18:21:03 INFO - Running setup.py install for mozhttpd 18:21:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:21:03 INFO - Running setup.py install for mozinfo 18:21:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:21:04 INFO - Running setup.py install for mozInstall 18:21:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:21:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:21:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:21:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:21:04 INFO - Running setup.py install for mozleak 18:21:04 INFO - Running setup.py install for mozlog 18:21:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:21:04 INFO - Running setup.py install for moznetwork 18:21:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:21:04 INFO - Running setup.py install for mozprocess 18:21:04 INFO - Running setup.py install for mozprofile 18:21:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:21:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:21:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:21:04 INFO - Running setup.py install for mozrunner 18:21:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:21:04 INFO - Running setup.py install for mozscreenshot 18:21:04 INFO - Running setup.py install for moztest 18:21:05 INFO - Running setup.py install for mozversion 18:21:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:21:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:21:05 INFO - Cleaning up... 18:21:05 INFO - Return code: 0 18:21:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:21: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:21:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:21: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:21:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:21:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bf61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f64b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f67f00>, '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 0x1f62ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f63050>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1502759692.568319-793261140', '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:21:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:21:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:21:05 INFO - Using env: {'DISPLAY': ':0', 18:21:05 INFO - 'HOME': '/home/cltbld', 18:21:05 INFO - 'LANG': 'en_US.UTF-8', 18:21:05 INFO - 'LANGUAGE': 'en_US:en', 18:21:05 INFO - 'LOGNAME': 'cltbld', 18:21:05 INFO - 'MAIL': '/var/mail/cltbld', 18:21:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:21:05 INFO - 'MOZ_NO_REMOTE': '1', 18:21:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:21:05 INFO - 'NO_EM_RESTART': '1', 18:21:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:21:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:21:05 INFO - 'PWD': '/builds/slave/test', 18:21:05 INFO - 'SHELL': '/bin/bash', 18:21:05 INFO - 'SHLVL': '1', 18:21:05 INFO - 'TERM': 'linux', 18:21:05 INFO - 'TMOUT': '86400', 18:21:05 INFO - 'USER': 'cltbld', 18:21:05 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1502759692.568319-793261140', 18:21:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:21:05 INFO - '_': '/tools/buildbot/bin/python'} 18:21:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:21:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:21:05 INFO - Running setup.py (path:/tmp/pip-NQ1yhQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:21:05 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 18:21:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:21:05 INFO - Running setup.py (path:/tmp/pip-K6S0qj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:21:05 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:21:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:21:05 INFO - Running setup.py (path:/tmp/pip-Ag1roJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:21:05 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:21:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:21:05 INFO - Running setup.py (path:/tmp/pip-WCt1VO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:21:05 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:21:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:21:05 INFO - Running setup.py (path:/tmp/pip-_MKT5K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:21:05 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:21:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:21:05 INFO - Running setup.py (path:/tmp/pip-bAJVCC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:21:05 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:21:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:21:05 INFO - Running setup.py (path:/tmp/pip-7Pk6sM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:21:05 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:21:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:21:05 INFO - Running setup.py (path:/tmp/pip-abHyv7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:21:05 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:21:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:21:05 INFO - Running setup.py (path:/tmp/pip-vaKSgn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:21:05 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:21:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:21:05 INFO - Running setup.py (path:/tmp/pip-MoMcJf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:21:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:21:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:21:06 INFO - Running setup.py (path:/tmp/pip-dtzgFk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:21:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 18:21:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:21:06 INFO - Running setup.py (path:/tmp/pip-Vvihsr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:21:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 18:21:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:21:06 INFO - Running setup.py (path:/tmp/pip-mYiWLw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:21:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 18:21:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:21:06 INFO - Running setup.py (path:/tmp/pip-bUZtQX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:21:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 18:21:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:21:06 INFO - Running setup.py (path:/tmp/pip-V5I9k9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:21:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 18:21:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:21:06 INFO - Running setup.py (path:/tmp/pip-q1aQLh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:21:06 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:21:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:21:06 INFO - Running setup.py (path:/tmp/pip-xZHjuD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:21:06 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:21:06 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:21:06 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:21:06 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:21:06 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:21:06 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:21:06 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:21:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:21: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:21: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:21: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:21: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:21:07 INFO - Downloading blessings-1.6.tar.gz 18:21:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:21:07 INFO - Installing collected packages: blessings 18:21:07 INFO - Running setup.py install for blessings 18:21:07 INFO - Successfully installed blessings 18:21:07 INFO - Cleaning up... 18:21:07 INFO - Return code: 0 18:21:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:21:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:21:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:21:08 INFO - Reading from file tmpfile_stdout 18:21:08 INFO - Current package versions: 18:21:08 INFO - argparse == 1.2.1 18:21:08 INFO - blessings == 1.6 18:21:08 INFO - blobuploader == 1.2.4 18:21:08 INFO - docopt == 0.6.1 18:21:08 INFO - functools32 == 3.2.3-2 18:21:08 INFO - jsonschema == 2.5.1 18:21:08 INFO - manifestparser == 1.1 18:21:08 INFO - mozInstall == 1.13 18:21:08 INFO - mozcrash == 1.0 18:21:08 INFO - mozdebug == 0.1 18:21:08 INFO - mozdevice == 0.51 18:21:08 INFO - mozfile == 1.2 18:21:08 INFO - mozhttpd == 0.7 18:21:08 INFO - mozinfo == 0.10 18:21:08 INFO - mozleak == 0.1 18:21:08 INFO - mozlog == 3.5 18:21:08 INFO - moznetwork == 0.27 18:21:08 INFO - mozprocess == 0.25 18:21:08 INFO - mozprofile == 0.28 18:21:08 INFO - mozrunner == 6.13 18:21:08 INFO - mozscreenshot == 0.1 18:21:08 INFO - mozsystemmonitor == 0.3 18:21:08 INFO - moztest == 0.8 18:21:08 INFO - mozversion == 1.4 18:21:08 INFO - psutil == 3.1.1 18:21:08 INFO - requests == 1.2.3 18:21:08 INFO - wsgiref == 0.1.2 18:21:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:21: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:21:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:21: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:21:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:21:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bf61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f64b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f67f00>, '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 0x1f62ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f63050>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1502759692.568319-793261140', '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:21:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 18:21:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:21:08 INFO - Using env: {'DISPLAY': ':0', 18:21:08 INFO - 'HOME': '/home/cltbld', 18:21:08 INFO - 'LANG': 'en_US.UTF-8', 18:21:08 INFO - 'LANGUAGE': 'en_US:en', 18:21:08 INFO - 'LOGNAME': 'cltbld', 18:21:08 INFO - 'MAIL': '/var/mail/cltbld', 18:21:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:21:08 INFO - 'MOZ_NO_REMOTE': '1', 18:21:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:21:08 INFO - 'NO_EM_RESTART': '1', 18:21:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:21:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:21:08 INFO - 'PWD': '/builds/slave/test', 18:21:08 INFO - 'SHELL': '/bin/bash', 18:21:08 INFO - 'SHLVL': '1', 18:21:08 INFO - 'TERM': 'linux', 18:21:08 INFO - 'TMOUT': '86400', 18:21:08 INFO - 'USER': 'cltbld', 18:21:08 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1502759692.568319-793261140', 18:21:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:21:08 INFO - '_': '/tools/buildbot/bin/python'} 18:21:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:21:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:21:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 18:21:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 18:21:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:21:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 18:21:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 18:21:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 18:21:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 18:21:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 18:21:08 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:21: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:21: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:21: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:21: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:21:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 18:21:09 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:21:09 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:21:09 INFO - Installing collected packages: simplejson 18:21:09 INFO - Running setup.py install for simplejson 18:21:09 INFO - building 'simplejson._speedups' extension 18:21:09 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:21:10 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 18:21:10 INFO - Successfully installed simplejson 18:21:10 INFO - Cleaning up... 18:21:10 INFO - Return code: 0 18:21:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:21:10 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:21:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:21:10 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:21:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:21:10 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 0x1bf61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f64b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f67f00>, '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 0x1f62ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f63050>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1502759692.568319-793261140', '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:21:10 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:21:10 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:21:10 INFO - Using env: {'DISPLAY': ':0', 18:21:10 INFO - 'HOME': '/home/cltbld', 18:21:10 INFO - 'LANG': 'en_US.UTF-8', 18:21:10 INFO - 'LANGUAGE': 'en_US:en', 18:21:10 INFO - 'LOGNAME': 'cltbld', 18:21:10 INFO - 'MAIL': '/var/mail/cltbld', 18:21:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:21:10 INFO - 'MOZ_NO_REMOTE': '1', 18:21:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:21:10 INFO - 'NO_EM_RESTART': '1', 18:21:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:21:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:21:10 INFO - 'PWD': '/builds/slave/test', 18:21:10 INFO - 'SHELL': '/bin/bash', 18:21:10 INFO - 'SHLVL': '1', 18:21:10 INFO - 'TERM': 'linux', 18:21:10 INFO - 'TMOUT': '86400', 18:21:10 INFO - 'USER': 'cltbld', 18:21:10 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1502759692.568319-793261140', 18:21:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:21:10 INFO - '_': '/tools/buildbot/bin/python'} 18:21:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:21:10 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:21:10 INFO - Cleaning up... 18:21:10 INFO - Return code: 0 18:21:10 INFO - Running post-action listener: _resource_record_post_action 18:21:10 INFO - Running post-action listener: _start_resource_monitoring 18:21:10 INFO - Starting resource monitoring. 18:21:10 INFO - [mozharness: 2017-08-15 01:21:10.872202Z] Finished create-virtualenv step (success) 18:21:10 INFO - [mozharness: 2017-08-15 01:21:10.872716Z] Running install step. 18:21:10 INFO - Running pre-action listener: _resource_record_pre_action 18:21:10 INFO - Running main action method: install 18:21:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:21:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:21:11 INFO - Reading from file tmpfile_stdout 18:21:11 INFO - Detecting whether we're running mozinstall >=1.0... 18:21:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:21:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:21:11 INFO - Reading from file tmpfile_stdout 18:21:11 INFO - Output received: 18:21:11 INFO - Usage: mozinstall [options] installer 18:21:11 INFO - Options: 18:21:11 INFO - -h, --help show this help message and exit 18:21:11 INFO - -d DEST, --destination=DEST 18:21:11 INFO - Directory to install application into. [default: 18:21:11 INFO - "/builds/slave/test"] 18:21:11 INFO - --app=APP Application being installed. [default: firefox] 18:21:11 INFO - mkdir: /builds/slave/test/build/application 18:21:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 18:21:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 18:21:29 INFO - Reading from file tmpfile_stdout 18:21:29 INFO - Output received: 18:21:29 INFO - /builds/slave/test/build/application/firefox/firefox 18:21:29 INFO - Running post-action listener: _resource_record_post_action 18:21:29 INFO - [mozharness: 2017-08-15 01:21:29.182840Z] Finished install step (success) 18:21:29 INFO - [mozharness: 2017-08-15 01:21:29.183126Z] Running setup-mitmproxy step. 18:21:29 INFO - Running pre-action listener: _resource_record_pre_action 18:21:29 INFO - Running main action method: setup_mitmproxy 18:21:29 INFO - Skipping: mitmproxy is not required 18:21:29 INFO - Running post-action listener: _resource_record_post_action 18:21:29 INFO - [mozharness: 2017-08-15 01:21:29.184277Z] Finished setup-mitmproxy step (success) 18:21:29 INFO - [mozharness: 2017-08-15 01:21:29.184503Z] Running run-tests step. 18:21:29 INFO - Running pre-action listener: _resource_record_pre_action 18:21:29 INFO - Running pre-action listener: _set_gcov_prefix 18:21:29 INFO - Running main action method: run_tests 18:21:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:21:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:21:29 INFO - Python 2.7.3 18:21:29 INFO - Return code: 0 18:21:29 INFO - grabbing minidump binary from tooltool 18:21:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:21:29 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f67f00>, '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 0x1f62ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f63050>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:21:29 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 18:21:29 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 18:21:29 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 18:21:29 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:21:29 INFO - Return code: 0 18:21:29 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:21:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:21:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:21:29 INFO - ENV: RUST_BACKTRACE is now full 18:21:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:21:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:21:29 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/QxuElLloREiYtzE68JGu4g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-062', '--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:21:29 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/QxuElLloREiYtzE68JGu4g/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-062 --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:21:29 INFO - Using env: {'DISPLAY': ':0', 18:21:29 INFO - 'HOME': '/home/cltbld', 18:21:29 INFO - 'LANG': 'en_US.UTF-8', 18:21:29 INFO - 'LANGUAGE': 'en_US:en', 18:21:29 INFO - 'LOGNAME': 'cltbld', 18:21:29 INFO - 'MAIL': '/var/mail/cltbld', 18:21:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:21:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:21:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:21:29 INFO - 'MOZ_NO_REMOTE': '1', 18:21:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:21:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:21:29 INFO - 'NO_EM_RESTART': '1', 18:21:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:21:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:21:29 INFO - 'PWD': '/builds/slave/test', 18:21:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:21:29 INFO - 'RUST_BACKTRACE': 'full', 18:21:29 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:21:29 INFO - 'SHELL': '/bin/bash', 18:21:29 INFO - 'SHLVL': '1', 18:21:29 INFO - 'TERM': 'linux', 18:21:29 INFO - 'TMOUT': '86400', 18:21:29 INFO - 'USER': 'cltbld', 18:21:29 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1502759692.568319-793261140', 18:21:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:21:29 INFO - '_': '/tools/buildbot/bin/python'} 18:21:29 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/QxuElLloREiYtzE68JGu4g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-062', '--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:21:29 INFO - mozversion application_buildid: 20170815002832 18:21:29 INFO - mozversion application_changeset: 92f3de33d97f55d54b2baad585b87e76aaa5ec58 18:21:29 INFO - mozversion application_display_name: Nightly 18:21:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:21:29 INFO - mozversion application_name: Firefox 18:21:29 INFO - mozversion application_remotingname: firefox 18:21:29 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:21:29 INFO - mozversion application_vendor: Mozilla 18:21:29 INFO - mozversion application_version: 57.0a1 18:21:29 INFO - mozversion platform_buildid: 20170815002832 18:21:29 INFO - mozversion platform_changeset: 92f3de33d97f55d54b2baad585b87e76aaa5ec58 18:21:29 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:21:29 INFO - mozversion platform_version: 57.0a1 18:21:29 INFO - using testdate: 1502760089 18:21:29 INFO - actual date: 1502760089 18:21:29 INFO - starting webserver on 'localhost:42890' 18:21:29 INFO - SUITE-START | Running 2 tests 18:21:29 INFO - TEST-START | dromaeo_css 18:21:29 INFO - Initialising browser for dromaeo_css test... 18:21:29 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:42890/getInfo.html -profile /tmp/tmpEQTdfx/profile 18:21:29 INFO - TEST-INFO | started process 19233 (/builds/slave/test/build/application/firefox/firefox http://localhost:42890/getInfo.html) 18:21:35 INFO - TEST-INFO | 19233: exit 0 18:21:35 INFO - Browser initialized. 18:21:35 INFO - Running cycle 1/1 for dromaeo_css test... 18:21:35 INFO - TEST-INFO | started process 19447 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEQTdfx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:21:36 INFO - PID 19447 | 18:21:36 INFO - PID 19447 | (/builds/slave/test/build/application/firefox/firefox:19495): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:21:36 INFO - PID 19447 | 18:21:46 INFO - PID 19447 | RSS: Main: 165441536 18:21:46 INFO - PID 19447 | 18:24:46 INFO - PID 19447 | Cycle 1(1): loaded http://localhost:42890/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42890/tests/dromaeo/cssquery-ext.html) 18:24:47 INFO - PID 19447 | RSS: Main: 170602496 18:24:47 INFO - PID 19447 | 18:27:49 INFO - PID 19447 | Cycle 1(1): loaded http://localhost:42890/tests/dromaeo/cssquery-ext.html (next: http://localhost:42890/tests/dromaeo/cssquery-jquery.html) 18:27:49 INFO - PID 19447 | RSS: Main: 168378368 18:27:49 INFO - PID 19447 | 18:30:51 INFO - PID 19447 | Cycle 1(1): loaded http://localhost:42890/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42890/tests/dromaeo/cssquery-mootools.html) 18:30:52 INFO - PID 19447 | RSS: Main: 171995136 18:30:52 INFO - PID 19447 | 18:33:53 INFO - PID 19447 | Cycle 1(1): loaded http://localhost:42890/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42890/tests/dromaeo/cssquery-prototype.html) 18:33:54 INFO - PID 19447 | RSS: Main: 169201664 18:33:54 INFO - PID 19447 | 18:36:57 INFO - PID 19447 | Cycle 1(1): loaded http://localhost:42890/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42890/tests/dromaeo/cssquery-yui.html) 18:36:58 INFO - PID 19447 | RSS: Main: 174120960 18:36:58 INFO - PID 19447 | 18:40:00 INFO - PID 19447 | Cycle 1(1): loaded http://localhost:42890/tests/dromaeo/cssquery-yui.html (next: http://localhost:42890/tests/dromaeo/cssquery-dojo.html) 18:40:00 INFO - PID 19447 | RSS: Main: 174661632 18:40:00 INFO - PID 19447 | 18:40:00 INFO - PID 19447 | __start_tp_report 18:40:00 INFO - PID 19447 | _x_x_mozilla_page_load 18:40:00 INFO - PID 19447 | _x_x_mozilla_page_load_details 18:40:00 INFO - PID 19447 | |i|pagename|runs| 18:40:00 INFO - PID 19447 | |0;dojo.html;3125.4217969675597;3181.427343078246;3182.2362632968093;3200.0719791260526;3212.9518057229147;6115.4190351916495;6124.938750612492;6154.938450615493;6188.226471691039;6351.61890286582;6223.377662233775;6276.184096067516;6293.181886354779;6333.461655759254;6342.238931328247;5693.373728889839;5716.657000579958;5746.885062298751;5831.970840145815;5856.414358564152;5677.48902598766;5692.57305702072;5788.305403351604;5810.82567522975;5812.883742325151;5720.771169153229;5746.166805813134;5771.422857714216;5816.214811000503;5829.271341082364;5611.831645050655;5657;5732.684702341369;5733.770649174028;5783.392743761887;5631.746571404296;5633.464820842054;5669.688167150846;5710.885782284373;5735.770569177228;6114.785982490636;6150.2927163376335;6210.099535567318;6218.564700470969;6227;6666.300038495985;6714.127163468766;6759.155105611799;6761.29006454325;6762.087118239024;6615.93384066158;6640.33596640337;6686.331366863324;6840.965795170992;6844.623545704984;6281.434670879643;6296.338884417162;6329.651869147149;6336.936630633634;6354.936450635527;6850.862982740417;6851.417629501449;6999.370056694922;7005.684744186524;7010.193827709777;6530.085787989685;6535.477161827138;6551.541392102507;6612.2726500084955;6636.30318816527;6674.032265321501;6699.899501507482;6708.262091169968;6708.798736037927;6723.529352945345;6587.769428069994;6626.436752875963;6634.469242460688;6642.96678516614;6701.5979041256705;6659.700313485808;6683.699233534503;6720.596764194164;6780.254172041072;6785.525013249124;4970.577500912391;5057.898842023139;5100.617453690914;5106.310648062464;5108.565771909355;4303.74177549348;4362.367456718758;4368.453943257093;4378.978105109518;4393.582609652078;5674.914876276859;5815.651060936358;5854;5863.149843272787;5900.498457631149;6783.287754785676;6793.626350550767;6827.419669328163;6870.931290687029;6893.551919125241;5858.384869588717;5865.472107510344;5939.2575928009;5946.613470124428;5962.07587823888;5859.355470898197;5925.05199168131;5936.050231962865;5948.494377977834;6001.909971350433;6551.344865513506;6551.475881929434;6578.703958321887;6584.176977877765;6584.275729669828;6643.70103345341;6694.397504224643;6704.061431399706;6782.084418603426;6784.7625333115075;6495.480361570969;6517.804465866032;6565.409113179837;6574.046763219402;6648.5678430901835;6557.967210163919;6609.504287178577;6657.768312862136;6671.26616072222;6672.899906501503;6719.260881302955;6752.3922846944015;6778.661066946534;6809.761658341934;6848.212455567674;6526.24948130971;6549.803505894831;6550.344965503412;6563.9343606563325;6615.470762338905;6532.281449040506;6540.901886471802;6548.279689234276;6563.278039415565;6634;6391.360863913572;6479.060536222127;6487.3188315226225;6578.24350199714;6578.605283682994;6439.162908821823;6600.042993765973;6606.6036037837885;6618;6620.040094186413;6281.246250449975;6311.0217916223055;6387.233531976007;6400.51996100285;6400.871982560229 18:40:00 INFO - PID 19447 | |1;ext.html;818.1899919080108;871.4771137317608;888.493558671557;894.5214310343968;1153.849999500066;29529.261768455774;30172;39626.60373396265;40286.79856600713;40374;39946;40250.19499610006;40304.19391612166;40335;40419.39370909439;7102.360787529121;7444.813879653011;7577.128630207514;7600.391968642523;7664.65509052094;7401.0748656417945;7503;7520.059992500937;7525.66134523945;7555.395568354518;7481.438892083088;7522.285382888617;7526.36025937796;7543.811404714871;7573.356264717478;7412.629368531579;7464.141623713288;7464.216257292987;7474.103107627066;7481.177070522265;7381.9630901845685;7455.366293865028;7458;7462.179160292363;7532.983047288655;36613.450798238046;36786.8160659195;36904;36955.63044369549;37020.074498137765;3483.442649176145;3531.611522732497;3532.4171511700797;3545.290941811648;3549.5208146900095;29666;29690;29733.256668583243;29740.70259297401;29752.256193595116;4947.703137811743;4977.850664480072;4999.8000079996755;5045.243213517965;5063.367079115111;191110;191655;192422.9999999986;192654;194500;47079.29381059287;47173;47210.76394618005;47236.29145562819;47271.5272847274;48875;49033.75483122633;49391.75304123528;49404.752976235606;49423.25865112026;16327.102009389597;16471;16537.83462165387;16556.6688666226;16592.585185370463;12039.518419263131;12332.075094367958;13513;13821.101628394323;13897.16617002983;14180.716385672435;14200.218987955555;14259.42962281497;14326.355314010894;14443.277836108153;5586.581006424534;5839.211706419664;5849.532037436995;5861.912071318934;5904.291485021825;1012.5899010900597;1017.2574020964737;1026.3226270661328;1026.6714651311509;1029.1509504658686;22201.555968880533;22615.208467703553;22889.427764306027;22892.771072289397;22911.427214319774;1116;1128.4019469681082;1136.9260998035102;1145.5818626201376;1153.411760002405;11799.646010619696;12178.512859485698;12193.39033048344;12198.14612977083;12243.450463275036;217398;218923;219856;220255;221105;49396;49614.25578616252;49624.50375496271;49844.75077624661;50015.24977125346;1435.5836807325759;1474.8893832962356;1477.128494188434;1503.6015455904194;1507.2539093148741;21761.238356658076;22160.445988850406;22250.331225157854;22255.221067263217;22398.66402004004;35012.77455289053;35039.47440788441;35340;35393.469097964065;35417;189027;190094;190258;190332;190342;29423.558646620746;30039.849800750857;32319.838400807846;32505.512417314232;32646.34707305888;39722.40416393814;39924.60075399209;40011.399829002;40088.799556002035;40390.79804600958;30821.537676935313;30897.22756931005;31026;31078.844605776827;31262.37475250528;30479.847600761856;30824;30852.382952341275;30852.69147308588;30911.2272193197;32846.835765821015;33317.50023749597;33345;33605.495917560766;33627.83186084054;29049.12852614425;29109.7089029107;29659.703402965693;29667.85166074156;29694;22653;22753;22832.31503054911;22865.31404057881;23228 18:40:00 INFO - PID 19447 | |2;jquery.html;551.4457969740411;555.1617058242053;565.1240577105485;575.9510441612463;580.489169530813;21098.15607375705;21809.890950545225;21842.235521756742;21847.890760546175;21872.01575929083;24576.38559036025;25682.74317256827;26806.06178783743;26853.731462685366;26928.865355673242;6370.904436433457;6466.612003279794;6468.773592924249;6522.967385163068;6527.967360163192;6116.113163591299;6316.494680425555;6422.935770642281;6433;6454.322296158883;6417.197850268716;6419.518536109787;6423.325550817167;6426;6427.453666438359;6412.102305677209;6421.775237866699;6432.2602900666325;6433.420992110709;6435.6782160892235;5988;5988.9101663475085;5990.820275391745;5992.250968628921;5995.400459954013;28806.567901481496;29613.111606651833;30178.396432071237;30997.535036974463;31150;1933.5939452714947;1941.5049162463622;1945.338584881133;1948.7369205157263;1958.0503455823914;18810.435686929413;19671.409857704293;19696.507587310432;19769.11039003248;19775.604487910306;2352.6118190498546;2367.573836709392;2378.0851959546208;2390.9521809563885;2451.031842422251;136133.3193334037;138465.307673462;139531;139777;139971;13605.863941360658;14748.18884961316;14816.925915370353;14866.182359970306;15055.53722643798;22932.738699371694;23201.419964501023;23229;23283.18508852182;23797.524049518914;10357.326773759682;10629.149668026694;10683.83974240387;10713.571457141783;10765.3540787553;10200.23498237635;10209.591616335412;10256.230782691327;10342.586296548054;10386.688399348031;9188.816223675489;9305.25557955362;9368.95315523418;9379.155875971195;9694.127528522326;2613.4381108061857;2613.5756012972597;2620.908268210642;2656.136755554414;2738.069056520753;943.6366998705548;976.5947131940165;979.9412035277907;984.3159004491953;987.2003677021636;19889.701654475193;19987;20403.285884993955;20673.896630516752;20934.057967391505;1048.8688913885765;1055.8099542082346;1057.2229411382627;1062.4846949229618;1062.5643486170634;10874.130069594414;11321.547138114547;13527.729445411234;14136.08115472491;14489.78265326021;550362.992740143;554889;555867;557070;557934;116259.41870290594;118324.40837795756;119472;120116.39941800234;120429;1270.0791925853682;1288.9419976101099;1314.842218933715;1322.5172811923771;1335.8396992360979;21335.78664213338;21498.570028599654;21692;21702.78297217071;21954.89022554877;57851;58769.412305876394;59122.40877591341;60280.698596506736;61759.691201543705;67395;67935.66032169807;68213;68258.65870670615;68968.31031689618;14505.854941450873;14960.775588366401;15025.248737563297;15036.173010484528;15127.16800575935;23857.284281471584;23928.641070384307;23995.16016939399;24063.639045414682;24276;18853.24587016504;18982.810171898105;19156.371708404942;19205.615887681888;19322.516937076685;16665;17070.658586828442;17300.788944773743;17486;17503.29986800514;18173.90913045479;19403.708944366128;19449.222031119163;19671.901640491706;19696.704549432056;14708.41166353334;14924.552263432115;14937.626559336104;14948;14962.177080260255;18170.636587268444;18443.07784610737;18582.44252672422;18583.721244181077;18633.161507732457 18:40:00 INFO - PID 19447 | |3;mootools.html;1327.820744199533;1370.3696299702137;1408.8802451791598;1411.9929400352996;1414.4130185972822;13921.930390348047;14102.435902563886;14520.41918323267;14560.781588276184;14585.416583336653;14530.636734081654;14764.926175369108;14805.55583332499;14816.777748333783;14827.55517334479;2646.0209722402737;2689.663792025997;2693.4209145033815;2706.174616741893;2708.2958430807935;2586.379268975442;2683.4633073385303;2795.51078561252;2798.790090743192;2800.635917330744;2710.891564337434;2716;2745.752882240598;2746.258510202244;2746.3408781892404;2658.3354161459633;2680.8123431359813;2701.770349520293;2702.6486556747695;2706.134037108126;2580.1743442098536;2594.0531705927388;2613.6732908386452;2614.307208589725;2633.3416645838543;13924.860751392458;13994.510192143227;14019.579412617637;14248.287585620781;14250.073745206528;7433.331000209953;7651.120121186079;7664.348530374925;7678.808029799244;7726.420518461082;4941.357623508957;5107.693538387709;5228.215767634847;5391.353037635509;5425.782968681248;1673.9246733897003;1691.2981112838158;1691.621328617173;1758.2000189913686;1760.6302676437963;23808.523829523314;23896.641550376586;24009.63985540201;24052.75947240523;24060.639090413482;6537.542372033912;6900.723971041202;7212.567245965259;7261.41908647307;7318.780436586911;16657.750133748003;16802.915985419993;16817.243224054946;16822.411215607393;16834.24245908937;7849.843003140019;7951.562664053533;8330.208630180123;8336.749897503085;8347.04009038956;7351.081114860643;7457.47797654159;7468.514546554566;7475.327220550068;7485.663145158481;5032.521910418504;5153.639245252796;5169.638125331266;5259.579233661298;5260.553347829377;1442.69703362293;1467.149053548939;1480.570634516;1494.9177795221367;1513.4173343262923;845.1675100026468;861.67687117331;871.0288028847916;874.3136637739472;887.7425546591545;6915.273896240923;7010.894836577456;7010.929890701028;7044.92955070453;7047.929520704727;730.1894896664697;731.2614259597739;734.1997223026926;738.7672883041824;741.4957828676444;4777.761111944389;4945.975270123627;5127.359080114986;5182.8963420732125;5342.519173274424;19430.805691943184;20093.49766255855;20390.69413958792;20409.081591328424;20481.38555843327;19016.904915475334;19460.4161875144;19478.22087116528;19737.013149342474;20037.198512059353;11042.33745975244;11128.721781955353;11140.610078647209;11191.104711623051;11197.104231661448;7668.357530173123;7885.132635410215;7932.484388514728;7957.56233407145;8020.478668886503;16720.498385048468;16833.326666933186;16871.072091035112;16919.15404229759;17056.31774728997;17170.484885453458;17243.310267589157;17313.74029389535;17376.131193440026;17406.47780566585;10593.788124237626;10594.15246780271;10672.8932710675;10732.892671073188;11346.149038821955;13334.26661533625;13342.06605537603;13365.799513007507;13438.529651462542;13503.39234734459;10699.572017119539;10723.410212438392;10814.89185108139;10832.729181770519;10976.396298203676;10125.898741012496;10201.897981020094;10229.74425639365;10501.31741436804;10540.84188737185;9555.665551705657;9619.903800961902;9637.662681806103;9763.1701305387;9801.215902727907;6937.132858392701;7071.964640176766;7073.575585464889;7117.8576428470105;7153.463490238357;7086.185088714864;7398.59307738058;7418.777436676908;7503.587302698404;7511.436642251743 18:40:00 INFO - PID 19447 | |4;prototype.html;503.56441677948584;503.9961827508052;521.3535216331748;522.7307936412748;533.9866503337419;6109.541784366168;6134.969325153374;6211.968940155299;6237.157983672203;6276.403741644543;6038.57729958903;6079.148919151322;6114.388561143895;6177.444030037295;6213.471854892339;4796.784144713496;4858.392700912386;4954.97522512387;5042.47054059324;5054.393472783271;4909.5581397674205;4916.262560615901;4930.926036109461;4937.481564435719;4955.355803745526;4884.829030983917;4900.411950565937;4906.1168989581865;4919.950800491985;4940;4879.268109783526;4899.510048995107;4921.827736029221;4946.282788995575;4954.529319714621;4418.64650827933;4436.489803672554;4464.214314274283;4464.352668863029;4491.371208030879;6051.8184454466;6080.209572755558;6156.445919867234;6171.783987560414;6187.5149964008315;5584.86037849053;5596.244506991545;5601.551875849922;5603.60774745768;5616.494515493625;6524.184476940382;6554.246261679872;6556.655885543452;6558;6567.441767449773;4822.397200349957;4825.831095911662;4827.131116399047;4856.854294371175;4861.951380486185;6573.375529324707;6662.700178491979;6752.459803215731;6757.324267573253;6876.553024053421;4815.831445899376;4856.927146092811;4880.560749532559;4898;4937.580305674058;4923.852284431473;4924.679895806761;4959.925601115986;4970.931249781314;4991.425986011583;5545.528630066489;5545.833624991256;5550.333959924754;5550.9192398219375;5566.4990150886615;6479.384458476437;6520.445762110179;6548.8035358939305;6562.967185164043;6633.237177724527;1712.3236321652878;1721.4147189955474;1729.2218501674297;1736.9305227790999;1769.9823001770076;1148.1274231583889;1191.766521650088;1205.9035277177804;1218.482145088334;1219.9817002744967;5715.571332150105;5719.456651618089;5739.225204597409;5765.1928729977835;5780.312154848016;6562.770303039466;6579.374959378852;6596.229583574814;6663.733450661919;6670.899936500957;5723.885522289531;5742.397048309951;5752.395998420189;5766.192733017381;5771.134329850472;3527.3650742866016;3546.8226588670977;3547.6807087362263;3559.483874838134;3576.320499105214;6401.4238718514625;6488.610683359014;6504.967475162689;6531.836704082436;6538.673066346663;6879.793606191823;6892.586444813327;6893.862122757517;6946.131733533309;6968.198657154492;6495.447886929569;6569.408753212234;6581.572197807127;6596.96701516499;6616.636085015371;6765.695543700447;6790.435021613693;6792.966035169792;6801.5238933274195;6826.317368263134;6469;6520.413162815369;6693.933060669331;6704.6982885769285;6711.46308295345;5929.703514824155;6421.71102300407;6435.485161187;6465;6477.676116194266;4901.58336541398;4910.656254062182;4912.680675756064;4921.63087768426;4931.186354251508;6422.743090276336;6556.770513032021;6615.6030638161865;6669.265990842319;6691.360616648953;6381.426401215149;6410.230772307353;6497.870042599027;6500.154979852589;6576.80269591913;6439.420452159328;6450.354964503512;6460.25707043696;6473.8705225896165;6567.2447668518735;6361.332060133805;6413.877571424889;6415.422611964946;6417.903731444126;6477.740890364333;6566.277709451861;6569.4744420447205;6631.403173714196;6651.368120028589;6678.265390807104;6294.276158241861;6305.621662700252;6311.147995020613;6316.778912738032;6316.873662526632 18:40:00 INFO - PID 19447 | |5;yui.html;909.5588639509838;912.5528491039389;931.031727003916;940.1632547033141;941.7174847545737;18019.54951126221;18783.530411739714;18837.246510139594;18844;21675;20546.48633784156;20915.16339346424;21059;21076.36770896872;21093.050812713427;1033.1476531861208;1054.9947250263738;1057.5928267616985;1059.4755595979962;1061.6708820265703;1061.9628313009046;1065.121274948167;1065.9413732244723;1066.7119877633033;1067.236925598197;990.8464188050827;1084.5336505302716;1096.4901320885785;1096.8848270931558;1097.8408130821026;1091.4324551233394;1091.9781604367909;1093.3330668292335;1095.6767753512734;1097.6926460591046;789.4000559574707;789.6288744290174;790.0795573157264;790.9485883417559;797.9082405523382;19481;19908.601827963503;20017.29939452127;20104.29634962784;20133.798662013338;20596.485087872774;20663.483412914647;20728.170873165054;21089.789102108934;21154.365369038955;16265.268062937197;17053;17223.56941076483;17239.22423490946;17305.48083557483;3599.6940260077927;3646.4348026056005;3647.215848592565;3647.908802279938;3650.142216579102;123101;124237.37881310536;130385;131768;132576;16581;16643.251053702614;16674.83325166757;16700.331986720394;16823.41118060862;31460.37079258402;31559.684403156134;31825.04524864258;32143.35713285768;32145;7762.534247945028;7861.52830830152;7940.722074727357;8134.837303253902;8818.206361427503;4179;4270.765107919032;4271.466066741657;4276.230278549829;4295.334223195409;5027.421846487628;5061.164907790173;5144.356955380577;5158.484151584811;5171.741412929338;974.3958745577787;978.4178413843894;998.7253505285959;1002.7092143278513;1007.0282177698556;607.9787207447806;668.2649086005355;697.5919087333966;699.9825004374932;714.8463080437731;11642.24325418845;12431.93784031074;12608;13224.735505290033;13615.387307571184;707.2883621635654;707.3033062433499;712.9394001509826;723.2622724820759;733.3949491669288;5854.853628659318;5860.208871802337;5957.195778569838;6042.4561789438285;6229.968850155811;108307.45846270719;108686;111316;113036;114384;96075.51962240144;96546;96748.51625741967;97741;98413.50793245988;83049.67801287881;84954.5752271247;85928.57035714781;86145.56927215324;86155.13844861597;12226.14416990802;13146.276954767198;13157.934210328887;13192.142510736772;13258.602241932758;38153.427698585096;38185;39165.216695666495;39792;39951.80024099861;70070.29929700842;70415;70795;72784;77017.22982770325;18553.53616159607;19545;19620.705689414386;19622.509437263612;19783.703244451055;18883.339083132025;19784.406467805988;20359.4910127248;22296.10815567424;22825.657615135453;19539.60920781548;19816.108275127943;19910.60178796445;20083.799162008192;20241;19768.40694779159;20047.0978805957;20181.697274541188;20359.083841226886;20526.794732052487;18566.814331857047;18698.813011869708;18940.242390304233;19152.904235478734;19377.321793737152;13990.930045349709;14422.783658245344;15032.924835375754;15047.699046018799;15373.92382533212;14810.703785924437;14931.328090235747;15095.245237738289;15430.382784688487;15681.843181568038 18:40:00 INFO - PID 19447 | __end_tp_report 18:40:00 INFO - PID 19447 | __start_cc_report 18:40:00 INFO - PID 19447 | _x_x_mozilla_cycle_collect,384 18:40:00 INFO - PID 19447 | __end_cc_report 18:40:00 INFO - PID 19447 | __startTimestamp1502761200654__endTimestamp 18:40:00 INFO - PID 19447 | 18:40:00 INFO - PID 19447 | ------- Summary: start ------- 18:40:00 INFO - PID 19447 | Number of tests: 6 18:40:00 INFO - PID 19447 | 18:40:00 INFO - PID 19447 | [#0] dojo.html Cycles:165 Average:6171.28 Median:6479.06 stddev:765.17 (11.8%) stddev-sans-first:729.26 18:40:00 INFO - PID 19447 | Values: 3125.4 3181.4 3182.2 3200.1 3213.0 6115.4 6124.9 6154.9 6188.2 6351.6 6223.4 6276.2 6293.2 6333.5 6342.2 5693.4 5716.7 5746.9 5832.0 5856.4 5677.5 5692.6 5788.3 5810.8 5812.9 5720.8 5746.2 5771.4 5816.2 5829.3 5611.8 5657.0 5732.7 5733.8 5783.4 5631.7 5633.5 5669.7 5710.9 5735.8 6114.8 6150.3 6210.1 6218.6 6227.0 6666.3 6714.1 6759.2 6761.3 6762.1 6615.9 6640.3 6686.3 6841.0 6844.6 6281.4 6296.3 6329.7 6336.9 6354.9 6850.9 6851.4 6999.4 7005.7 7010.2 6530.1 6535.5 6551.5 6612.3 6636.3 6674.0 6699.9 6708.3 6708.8 6723.5 6587.8 6626.4 6634.5 6643.0 6701.6 6659.7 6683.7 6720.6 6780.3 6785.5 4970.6 5057.9 5100.6 5106.3 5108.6 4303.7 4362.4 4368.5 4379.0 4393.6 5674.9 5815.7 5854.0 5863.1 5900.5 6783.3 6793.6 6827.4 6870.9 6893.6 5858.4 5865.5 5939.3 5946.6 5962.1 5859.4 5925.1 5936.1 5948.5 6001.9 6551.3 6551.5 6578.7 6584.2 6584.3 6643.7 6694.4 6704.1 6782.1 6784.8 6495.5 6517.8 6565.4 6574.0 6648.6 6558.0 6609.5 6657.8 6671.3 6672.9 6719.3 6752.4 6778.7 6809.8 6848.2 6526.2 6549.8 6550.3 6563.9 6615.5 6532.3 6540.9 6548.3 6563.3 6634.0 6391.4 6479.1 6487.3 6578.2 6578.6 6439.2 6600.0 6606.6 6618.0 6620.0 6281.2 6311.0 6387.2 6400.5 6400.9 18:40:00 INFO - PID 19447 | 18:40:00 INFO - PID 19447 | [#1] ext.html Cycles:180 Average:36173.78 Median:32412.68 stddev:52034.79 (160.5%) stddev-sans-first:52113.04 18:40:00 INFO - PID 19447 | Values: 818.2 871.5 888.5 894.5 1153.8 29529.3 30172.0 39626.6 40286.8 40374.0 39946.0 40250.2 40304.2 40335.0 40419.4 7102.4 7444.8 7577.1 7600.4 7664.7 7401.1 7503.0 7520.1 7525.7 7555.4 7481.4 7522.3 7526.4 7543.8 7573.4 7412.6 7464.1 7464.2 7474.1 7481.2 7382.0 7455.4 7458.0 7462.2 7533.0 36613.5 36786.8 36904.0 36955.6 37020.1 3483.4 3531.6 3532.4 3545.3 3549.5 29666.0 29690.0 29733.3 29740.7 29752.3 4947.7 4977.9 4999.8 5045.2 5063.4 191110.0 191655.0 192423.0 192654.0 194500.0 47079.3 47173.0 47210.8 47236.3 47271.5 48875.0 49033.8 49391.8 49404.8 49423.3 16327.1 16471.0 16537.8 16556.7 16592.6 12039.5 12332.1 13513.0 13821.1 13897.2 14180.7 14200.2 14259.4 14326.4 14443.3 5586.6 5839.2 5849.5 5861.9 5904.3 1012.6 1017.3 1026.3 1026.7 1029.2 22201.6 22615.2 22889.4 22892.8 22911.4 1116.0 1128.4 1136.9 1145.6 1153.4 11799.6 12178.5 12193.4 12198.1 12243.5 217398.0 218923.0 219856.0 220255.0 221105.0 49396.0 49614.3 49624.5 49844.8 50015.2 1435.6 1474.9 1477.1 1503.6 1507.3 21761.2 22160.4 22250.3 22255.2 22398.7 35012.8 35039.5 35340.0 35393.5 35417.0 189027.0 190094.0 190258.0 190332.0 190342.0 29423.6 30039.8 32319.8 32505.5 32646.3 39722.4 39924.6 40011.4 40088.8 40390.8 30821.5 30897.2 31026.0 31078.8 31262.4 30479.8 30824.0 30852.4 30852.7 30911.2 32846.8 33317.5 33345.0 33605.5 33627.8 29049.1 29109.7 29659.7 29667.9 29694.0 22653.0 22753.0 22832.3 22865.3 23228.0 18:40:00 INFO - PID 19447 | 18:40:00 INFO - PID 19447 | [#2] jquery.html Cycles:180 Average:36992.92 Median:21826.06 stddev:92723.57 (424.8%) stddev-sans-first:92943.32 18:40:00 INFO - PID 19447 | Values: 551.4 555.2 565.1 576.0 580.5 21098.2 21809.9 21842.2 21847.9 21872.0 24576.4 25682.7 26806.1 26853.7 26928.9 6370.9 6466.6 6468.8 6523.0 6528.0 6116.1 6316.5 6422.9 6433.0 6454.3 6417.2 6419.5 6423.3 6426.0 6427.5 6412.1 6421.8 6432.3 6433.4 6435.7 5988.0 5988.9 5990.8 5992.3 5995.4 28806.6 29613.1 30178.4 30997.5 31150.0 1933.6 1941.5 1945.3 1948.7 1958.1 18810.4 19671.4 19696.5 19769.1 19775.6 2352.6 2367.6 2378.1 2391.0 2451.0 136133.3 138465.3 139531.0 139777.0 139971.0 13605.9 14748.2 14816.9 14866.2 15055.5 22932.7 23201.4 23229.0 23283.2 23797.5 10357.3 10629.1 10683.8 10713.6 10765.4 10200.2 10209.6 10256.2 10342.6 10386.7 9188.8 9305.3 9369.0 9379.2 9694.1 2613.4 2613.6 2620.9 2656.1 2738.1 943.6 976.6 979.9 984.3 987.2 19889.7 19987.0 20403.3 20673.9 20934.1 1048.9 1055.8 1057.2 1062.5 1062.6 10874.1 11321.5 13527.7 14136.1 14489.8 550363.0 554889.0 555867.0 557070.0 557934.0 116259.4 118324.4 119472.0 120116.4 120429.0 1270.1 1288.9 1314.8 1322.5 1335.8 21335.8 21498.6 21692.0 21702.8 21954.9 57851.0 58769.4 59122.4 60280.7 61759.7 67395.0 67935.7 68213.0 68258.7 68968.3 14505.9 14960.8 15025.2 15036.2 15127.2 23857.3 23928.6 23995.2 24063.6 24276.0 18853.2 18982.8 19156.4 19205.6 19322.5 16665.0 17070.7 17300.8 17486.0 17503.3 18173.9 19403.7 19449.2 19671.9 19696.7 14708.4 14924.6 14937.6 14948.0 14962.2 18170.6 18443.1 18582.4 18583.7 18633.2 18:40:00 INFO - PID 19447 | 18:40:00 INFO - PID 19447 | [#3] mootools.html Cycles:180 Average:8852.58 Median:2639.68 stddev:6111.67 (231.5%) stddev-sans-first:6102.66 18:40:00 INFO - PID 19447 | Values: 1327.8 1370.4 1408.9 1412.0 1414.4 13921.9 14102.4 14520.4 14560.8 14585.4 14530.6 14764.9 14805.6 14816.8 14827.6 2646.0 2689.7 2693.4 2706.2 2708.3 2586.4 2683.5 2795.5 2798.8 2800.6 2710.9 2716.0 2745.8 2746.3 2746.3 2658.3 2680.8 2701.8 2702.6 2706.1 2580.2 2594.1 2613.7 2614.3 2633.3 13924.9 13994.5 14019.6 14248.3 14250.1 7433.3 7651.1 7664.3 7678.8 7726.4 4941.4 5107.7 5228.2 5391.4 5425.8 1673.9 1691.3 1691.6 1758.2 1760.6 23808.5 23896.6 24009.6 24052.8 24060.6 6537.5 6900.7 7212.6 7261.4 7318.8 16657.8 16802.9 16817.2 16822.4 16834.2 7849.8 7951.6 8330.2 8336.7 8347.0 7351.1 7457.5 7468.5 7475.3 7485.7 5032.5 5153.6 5169.6 5259.6 5260.6 1442.7 1467.1 1480.6 1494.9 1513.4 845.2 861.7 871.0 874.3 887.7 6915.3 7010.9 7010.9 7044.9 7047.9 730.2 731.3 734.2 738.8 741.5 4777.8 4946.0 5127.4 5182.9 5342.5 19430.8 20093.5 20390.7 20409.1 20481.4 19016.9 19460.4 19478.2 19737.0 20037.2 11042.3 11128.7 11140.6 11191.1 11197.1 7668.4 7885.1 7932.5 7957.6 8020.5 16720.5 16833.3 16871.1 16919.2 17056.3 17170.5 17243.3 17313.7 17376.1 17406.5 10593.8 10594.2 10672.9 10732.9 11346.1 13334.3 13342.1 13365.8 13438.5 13503.4 10699.6 10723.4 10814.9 10832.7 10976.4 10125.9 10201.9 10229.7 10501.3 10540.8 9555.7 9619.9 9637.7 9763.2 9801.2 6937.1 7072.0 7073.6 7117.9 7153.5 7086.2 7398.6 7418.8 7503.6 7511.4 18:40:00 INFO - PID 19447 | 18:40:00 INFO - PID 19447 | [#4] prototype.html Cycles:180 Average:5469.70 Median:6111.97 stddev:1543.39 (25.3%) stddev-sans-first:1502.03 18:40:00 INFO - PID 19447 | Values: 503.6 504.0 521.4 522.7 534.0 6109.5 6135.0 6212.0 6237.2 6276.4 6038.6 6079.1 6114.4 6177.4 6213.5 4796.8 4858.4 4955.0 5042.5 5054.4 4909.6 4916.3 4930.9 4937.5 4955.4 4884.8 4900.4 4906.1 4920.0 4940.0 4879.3 4899.5 4921.8 4946.3 4954.5 4418.6 4436.5 4464.2 4464.4 4491.4 6051.8 6080.2 6156.4 6171.8 6187.5 5584.9 5596.2 5601.6 5603.6 5616.5 6524.2 6554.2 6556.7 6558.0 6567.4 4822.4 4825.8 4827.1 4856.9 4862.0 6573.4 6662.7 6752.5 6757.3 6876.6 4815.8 4856.9 4880.6 4898.0 4937.6 4923.9 4924.7 4959.9 4970.9 4991.4 5545.5 5545.8 5550.3 5550.9 5566.5 6479.4 6520.4 6548.8 6563.0 6633.2 1712.3 1721.4 1729.2 1736.9 1770.0 1148.1 1191.8 1205.9 1218.5 1220.0 5715.6 5719.5 5739.2 5765.2 5780.3 6562.8 6579.4 6596.2 6663.7 6670.9 5723.9 5742.4 5752.4 5766.2 5771.1 3527.4 3546.8 3547.7 3559.5 3576.3 6401.4 6488.6 6505.0 6531.8 6538.7 6879.8 6892.6 6893.9 6946.1 6968.2 6495.4 6569.4 6581.6 6597.0 6616.6 6765.7 6790.4 6793.0 6801.5 6826.3 6469.0 6520.4 6693.9 6704.7 6711.5 5929.7 6421.7 6435.5 6465.0 6477.7 4901.6 4910.7 4912.7 4921.6 4931.2 6422.7 6556.8 6615.6 6669.3 6691.4 6381.4 6410.2 6497.9 6500.2 6576.8 6439.4 6450.4 6460.3 6473.9 6567.2 6361.3 6413.9 6415.4 6417.9 6477.7 6566.3 6569.5 6631.4 6651.4 6678.3 6294.3 6305.6 6311.1 6316.8 6316.9 18:40:00 INFO - PID 19447 | 18:40:00 INFO - PID 19447 | [#5] yui.html Cycles:180 Average:24169.59 Median:20359.29 stddev:32333.58 (158.8%) stddev-sans-first:32377.11 18:40:00 INFO - PID 19447 | Values: 909.6 912.6 931.0 940.2 941.7 18019.5 18783.5 18837.2 18844.0 21675.0 20546.5 20915.2 21059.0 21076.4 21093.1 1033.1 1055.0 1057.6 1059.5 1061.7 1062.0 1065.1 1065.9 1066.7 1067.2 990.8 1084.5 1096.5 1096.9 1097.8 1091.4 1092.0 1093.3 1095.7 1097.7 789.4 789.6 790.1 790.9 797.9 19481.0 19908.6 20017.3 20104.3 20133.8 20596.5 20663.5 20728.2 21089.8 21154.4 16265.3 17053.0 17223.6 17239.2 17305.5 3599.7 3646.4 3647.2 3647.9 3650.1 123101.0 124237.4 130385.0 131768.0 132576.0 16581.0 16643.3 16674.8 16700.3 16823.4 31460.4 31559.7 31825.0 32143.4 32145.0 7762.5 7861.5 7940.7 8134.8 8818.2 4179.0 4270.8 4271.5 4276.2 4295.3 5027.4 5061.2 5144.4 5158.5 5171.7 974.4 978.4 998.7 1002.7 1007.0 608.0 668.3 697.6 700.0 714.8 11642.2 12431.9 12608.0 13224.7 13615.4 707.3 707.3 712.9 723.3 733.4 5854.9 5860.2 5957.2 6042.5 6230.0 108307.5 108686.0 111316.0 113036.0 114384.0 96075.5 96546.0 96748.5 97741.0 98413.5 83049.7 84954.6 85928.6 86145.6 86155.1 12226.1 13146.3 13157.9 13192.1 13258.6 38153.4 38185.0 39165.2 39792.0 39951.8 70070.3 70415.0 70795.0 72784.0 77017.2 18553.5 19545.0 19620.7 19622.5 19783.7 18883.3 19784.4 20359.5 22296.1 22825.7 19539.6 19816.1 19910.6 20083.8 20241.0 19768.4 20047.1 20181.7 20359.1 20526.8 18566.8 18698.8 18940.2 19152.9 19377.3 13990.9 14422.8 15032.9 15047.7 15373.9 14810.7 14931.3 15095.2 15430.4 15681.8 18:40:00 INFO - PID 19447 | -------- Summary: end -------- 18:40:00 INFO - PID 19447 | 18:40:01 INFO - TEST-INFO | 19447: exit 0 18:40:01 INFO - TEST-OK | dromaeo_css | took 1111698ms 18:40:01 INFO - TEST-START | kraken 18:40:01 INFO - Initialising browser for kraken test... 18:40:01 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:42890/getInfo.html -profile /tmp/tmpxdMObI/profile 18:40:01 INFO - TEST-INFO | started process 19814 (/builds/slave/test/build/application/firefox/firefox http://localhost:42890/getInfo.html) 18:40:06 INFO - TEST-INFO | 19814: exit 0 18:40:06 INFO - Browser initialized. 18:40:06 INFO - Running cycle 1/1 for kraken test... 18:40:06 INFO - TEST-INFO | started process 20028 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxdMObI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:40:07 INFO - PID 20028 | 18:40:07 INFO - PID 20028 | (/builds/slave/test/build/application/firefox/firefox:20076): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:40:07 INFO - PID 20028 | 18:40:18 INFO - PID 20028 | RSS: Main: 165548032 18:40:18 INFO - PID 20028 | 18:40:20 INFO - PID 20028 | Cycle 1(1): loaded http://localhost:42890/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42890/tests/kraken/driver.html?testName=audio-beat-detection) 18:40:21 INFO - PID 20028 | RSS: Main: 171892736 18:40:21 INFO - PID 20028 | 18:40:24 INFO - PID 20028 | Cycle 1(1): loaded http://localhost:42890/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42890/tests/kraken/driver.html?testName=audio-dft) 18:40:24 INFO - PID 20028 | RSS: Main: 172208128 18:40:24 INFO - PID 20028 | 18:40:27 INFO - PID 20028 | Cycle 1(1): loaded http://localhost:42890/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42890/tests/kraken/driver.html?testName=audio-fft) 18:40:28 INFO - PID 20028 | RSS: Main: 166727680 18:40:28 INFO - PID 20028 | 18:40:30 INFO - PID 20028 | Cycle 1(1): loaded http://localhost:42890/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42890/tests/kraken/driver.html?testName=audio-oscillator) 18:40:31 INFO - PID 20028 | RSS: Main: 167211008 18:40:31 INFO - PID 20028 | 18:40:33 INFO - PID 20028 | Cycle 1(1): loaded http://localhost:42890/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42890/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:40:33 INFO - PID 20028 | RSS: Main: 167493632 18:40:33 INFO - PID 20028 | 18:40:37 INFO - PID 20028 | Cycle 1(1): loaded http://localhost:42890/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42890/tests/kraken/driver.html?testName=imaging-darkroom) 18:40:37 INFO - PID 20028 | RSS: Main: 170205184 18:40:37 INFO - PID 20028 | 18:40:41 INFO - PID 20028 | Cycle 1(1): loaded http://localhost:42890/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42890/tests/kraken/driver.html?testName=imaging-desaturate) 18:40:41 INFO - PID 20028 | RSS: Main: 170184704 18:40:41 INFO - PID 20028 | 18:40:44 INFO - PID 20028 | Cycle 1(1): loaded http://localhost:42890/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42890/tests/kraken/driver.html?testName=json-parse-financial) 18:40:45 INFO - PID 20028 | RSS: Main: 171462656 18:40:45 INFO - PID 20028 | 18:40:46 INFO - PID 20028 | Cycle 1(1): loaded http://localhost:42890/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42890/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:40:47 INFO - PID 20028 | RSS: Main: 170819584 18:40:47 INFO - PID 20028 | 18:40:49 INFO - PID 20028 | Cycle 1(1): loaded http://localhost:42890/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42890/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:40:50 INFO - PID 20028 | RSS: Main: 171053056 18:40:50 INFO - PID 20028 | 18:40:52 INFO - PID 20028 | Cycle 1(1): loaded http://localhost:42890/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42890/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:40:52 INFO - PID 20028 | RSS: Main: 170897408 18:40:52 INFO - PID 20028 | 18:40:55 INFO - PID 20028 | Cycle 1(1): loaded http://localhost:42890/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42890/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:40:56 INFO - PID 20028 | RSS: Main: 171753472 18:40:56 INFO - PID 20028 | 18:40:59 INFO - PID 20028 | Cycle 1(1): loaded http://localhost:42890/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42890/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:40:59 INFO - PID 20028 | RSS: Main: 171241472 18:40:59 INFO - PID 20028 | 18:41:01 INFO - PID 20028 | Cycle 1(1): loaded http://localhost:42890/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42890/tests/kraken/driver.html?testName=ai-astar) 18:41:01 INFO - PID 20028 | RSS: Main: 173195264 18:41:01 INFO - PID 20028 | 18:41:01 INFO - PID 20028 | __start_tp_report 18:41:01 INFO - PID 20028 | _x_x_mozilla_page_load 18:41:01 INFO - PID 20028 | _x_x_mozilla_page_load_details 18:41:01 INFO - PID 20028 | |i|pagename|runs| 18:41:01 INFO - PID 20028 | |0;ai-astar;100;109;100;107;108;119;107;99;110;112 18:41:01 INFO - PID 20028 | |1;audio-beat-detection;145;120;145;172;122;144;146;120;171;119 18:41:01 INFO - PID 20028 | |2;audio-dft;164;163;163;163;162;163;164;165;170;168 18:41:01 INFO - PID 20028 | |3;audio-fft;77;89;77;172;75;81;76;76;157;76 18:41:01 INFO - PID 20028 | |4;audio-oscillator;80;79;133;79;80;81;132;79;78;130 18:41:01 INFO - PID 20028 | |5;imaging-gaussian-blur;115;114;115;114;115;114;114;114;114;115 18:41:01 INFO - PID 20028 | |6;imaging-darkroom;157;152;150;150;151;150;152;151;153;151 18:41:01 INFO - PID 20028 | |7;imaging-desaturate;94;95;94;94;94;93;95;95;94;95 18:41:01 INFO - PID 20028 | |8;json-parse-financial;55;62;54;55;54;64;53;54;54;53 18:41:01 INFO - PID 20028 | |9;json-stringify-tinderbox;55;54;55;54;55;55;55;55;55;55 18:41:01 INFO - PID 20028 | |10;stanford-crypto-aes;77;81;83;83;86;80;79;78;88;79 18:41:01 INFO - PID 20028 | |11;stanford-crypto-ccm;131;131;132;128;129;127;124;128;126;168 18:41:01 INFO - PID 20028 | |12;stanford-crypto-pbkdf2;187;180;177;174;184;184;178;176;179;179 18:41:01 INFO - PID 20028 | |13;stanford-crypto-sha256-iterative;68;69;71;73;68;67;69;68;67;69 18:41:01 INFO - PID 20028 | __end_tp_report 18:41:01 INFO - PID 20028 | __start_cc_report 18:41:01 INFO - PID 20028 | _x_x_mozilla_cycle_collect,941 18:41:01 INFO - PID 20028 | __end_cc_report 18:41:01 INFO - PID 20028 | __startTimestamp1502761261879__endTimestamp 18:41:01 INFO - PID 20028 | 18:41:01 INFO - PID 20028 | ------- Summary: start ------- 18:41:01 INFO - PID 20028 | Number of tests: 14 18:41:01 INFO - PID 20028 | 18:41:01 INFO - PID 20028 | [#0] ai-astar Cycles:10 Average:107.10 Median:109.50 stddev:6.19 (5.7%) stddev-sans-first:6.01 18:41:01 INFO - PID 20028 | Values: 100.0 109.0 100.0 107.0 108.0 119.0 107.0 99.0 110.0 112.0 18:41:01 INFO - PID 20028 | 18:41:01 INFO - PID 20028 | [#1] audio-beat-detection Cycles:10 Average:140.40 Median:145.00 stddev:20.14 (13.9%) stddev-sans-first:21.29 18:41:01 INFO - PID 20028 | Values: 145.0 120.0 145.0 172.0 122.0 144.0 146.0 120.0 171.0 119.0 18:41:01 INFO - PID 20028 | 18:41:01 INFO - PID 20028 | [#2] audio-dft Cycles:10 Average:164.50 Median:164.00 stddev:2.55 (1.6%) stddev-sans-first:2.70 18:41:01 INFO - PID 20028 | Values: 164.0 163.0 163.0 163.0 162.0 163.0 164.0 165.0 170.0 168.0 18:41:01 INFO - PID 20028 | 18:41:01 INFO - PID 20028 | [#3] audio-fft Cycles:10 Average:95.60 Median:76.50 stddev:36.72 (48.0%) stddev-sans-first:38.32 18:41:01 INFO - PID 20028 | Values: 77.0 89.0 77.0 172.0 75.0 81.0 76.0 76.0 157.0 76.0 18:41:01 INFO - PID 20028 | 18:41:01 INFO - PID 20028 | [#4] audio-oscillator Cycles:10 Average:95.10 Median:79.00 stddev:25.26 (32.0%) stddev-sans-first:26.19 18:41:01 INFO - PID 20028 | Values: 80.0 79.0 133.0 79.0 80.0 81.0 132.0 79.0 78.0 130.0 18:41:01 INFO - PID 20028 | 18:41:01 INFO - PID 20028 | [#5] imaging-gaussian-blur Cycles:10 Average:114.40 Median:114.50 stddev:0.52 (0.5%) stddev-sans-first:0.50 18:41:01 INFO - PID 20028 | Values: 115.0 114.0 115.0 114.0 115.0 114.0 114.0 114.0 114.0 115.0 18:41:01 INFO - PID 20028 | 18:41:01 INFO - PID 20028 | [#6] imaging-darkroom Cycles:10 Average:151.70 Median:151.50 stddev:2.11 (1.4%) stddev-sans-first:1.05 18:41:01 INFO - PID 20028 | Values: 157.0 152.0 150.0 150.0 151.0 150.0 152.0 151.0 153.0 151.0 18:41:01 INFO - PID 20028 | 18:41:01 INFO - PID 20028 | [#7] imaging-desaturate Cycles:10 Average:94.30 Median:94.50 stddev:0.67 (0.7%) stddev-sans-first:0.71 18:41:01 INFO - PID 20028 | Values: 94.0 95.0 94.0 94.0 94.0 93.0 95.0 95.0 94.0 95.0 18:41:01 INFO - PID 20028 | 18:41:01 INFO - PID 20028 | [#8] json-parse-financial Cycles:10 Average:55.80 Median:54.50 stddev:3.88 (7.1%) stddev-sans-first:4.11 18:41:01 INFO - PID 20028 | Values: 55.0 62.0 54.0 55.0 54.0 64.0 53.0 54.0 54.0 53.0 18:41:01 INFO - PID 20028 | 18:41:01 INFO - PID 20028 | [#9] json-stringify-tinderbox Cycles:10 Average:54.80 Median:55.00 stddev:0.42 (0.8%) stddev-sans-first:0.44 18:41:01 INFO - PID 20028 | Values: 55.0 54.0 55.0 54.0 55.0 55.0 55.0 55.0 55.0 55.0 18:41:01 INFO - PID 20028 | 18:41:01 INFO - PID 20028 | [#10] stanford-crypto-aes Cycles:10 Average:81.40 Median:82.00 stddev:3.57 (4.3%) stddev-sans-first:3.41 18:41:01 INFO - PID 20028 | Values: 77.0 81.0 83.0 83.0 86.0 80.0 79.0 78.0 88.0 79.0 18:41:01 INFO - PID 20028 | 18:41:01 INFO - PID 20028 | [#11] stanford-crypto-ccm Cycles:10 Average:132.40 Median:130.00 stddev:12.75 (9.8%) stddev-sans-first:13.51 18:41:01 INFO - PID 20028 | Values: 131.0 131.0 132.0 128.0 129.0 127.0 124.0 128.0 126.0 168.0 18:41:01 INFO - PID 20028 | 18:41:01 INFO - PID 20028 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.80 Median:179.50 stddev:4.05 (2.3%) stddev-sans-first:3.35 18:41:01 INFO - PID 20028 | Values: 187.0 180.0 177.0 174.0 184.0 184.0 178.0 176.0 179.0 179.0 18:41:01 INFO - PID 20028 | 18:41:01 INFO - PID 20028 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.90 Median:69.00 stddev:1.85 (2.7%) stddev-sans-first:1.94 18:41:01 INFO - PID 20028 | Values: 68.0 69.0 71.0 73.0 68.0 67.0 69.0 68.0 67.0 69.0 18:41:01 INFO - PID 20028 | -------- Summary: end -------- 18:41:01 INFO - PID 20028 | 18:41:02 INFO - PID 20028 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 18:41:02 INFO - TEST-INFO | 20028: exit 0 18:41:02 INFO - TEST-OK | kraken | took 61348ms 18:41:02 INFO - SUITE-END | took 1173s 18:41:03 INFO - Completed test suite (00:19:33) 18:41:03 INFO - javascript benchmark 18:41:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8495.069198320134, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3125.4217969675597, 3181.427343078246, 3182.2362632968093, 3200.0719791260526, 3212.9518057229147, 6115.4190351916495, 6124.938750612492, 6154.938450615493, 6188.226471691039, 6351.61890286582, 6223.377662233775, 6276.184096067516, 6293.181886354779, 6333.461655759254, 6342.238931328247, 5693.373728889839, 5716.657000579958, 5746.885062298751, 5831.970840145815, 5856.414358564152, 5677.48902598766, 5692.57305702072, 5788.305403351604, 5810.82567522975, 5812.883742325151, 5720.771169153229, 5746.166805813134, 5771.422857714216, 5816.214811000503, 5829.271341082364, 5611.831645050655, 5657.0, 5732.684702341369, 5733.770649174028, 5783.392743761887, 5631.746571404296, 5633.464820842054, 5669.688167150846, 5710.885782284373, 5735.770569177228, 6114.785982490636, 6150.2927163376335, 6210.099535567318, 6218.564700470969, 6227.0, 6666.300038495985, 6714.127163468766, 6759.155105611799, 6761.29006454325, 6762.087118239024, 6615.93384066158, 6640.33596640337, 6686.331366863324, 6840.965795170992, 6844.623545704984, 6281.434670879643, 6296.338884417162, 6329.651869147149, 6336.936630633634, 6354.936450635527, 6850.862982740417, 6851.417629501449, 6999.370056694922, 7005.684744186524, 7010.193827709777, 6530.085787989685, 6535.477161827138, 6551.541392102507, 6612.2726500084955, 6636.30318816527, 6674.032265321501, 6699.899501507482, 6708.262091169968, 6708.798736037927, 6723.529352945345, 6587.769428069994, 6626.436752875963, 6634.469242460688, 6642.96678516614, 6701.5979041256705, 6659.700313485808, 6683.699233534503, 6720.596764194164, 6780.254172041072, 6785.525013249124, 4970.577500912391, 5057.898842023139, 5100.617453690914, 5106.310648062464, 5108.565771909355, 4303.74177549348, 4362.367456718758, 4368.453943257093, 4378.978105109518, 4393.582609652078, 5674.914876276859, 5815.651060936358, 5854.0, 5863.149843272787, 5900.498457631149, 6783.287754785676, 6793.626350550767, 6827.419669328163, 6870.931290687029, 6893.551919125241, 5858.384869588717, 5865.472107510344, 5939.2575928009, 5946.613470124428, 5962.07587823888, 5859.355470898197, 5925.05199168131, 5936.050231962865, 5948.494377977834, 6001.909971350433, 6551.344865513506, 6551.475881929434, 6578.703958321887, 6584.176977877765, 6584.275729669828, 6643.70103345341, 6694.397504224643, 6704.061431399706, 6782.084418603426, 6784.7625333115075, 6495.480361570969, 6517.804465866032, 6565.409113179837, 6574.046763219402, 6648.5678430901835, 6557.967210163919, 6609.504287178577, 6657.768312862136, 6671.26616072222, 6672.899906501503, 6719.260881302955, 6752.3922846944015, 6778.661066946534, 6809.761658341934, 6848.212455567674, 6526.24948130971, 6549.803505894831, 6550.344965503412, 6563.9343606563325, 6615.470762338905, 6532.281449040506, 6540.901886471802, 6548.279689234276, 6563.278039415565, 6634.0, 6391.360863913572, 6479.060536222127, 6487.3188315226225, 6578.24350199714, 6578.605283682994, 6439.162908821823, 6600.042993765973, 6606.6036037837885, 6618.0, 6620.040094186413, 6281.246250449975, 6311.0217916223055, 6387.233531976007, 6400.51996100285, 6400.871982560229], "value": 6111.3189029030345, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [818.1899919080108, 871.4771137317608, 888.493558671557, 894.5214310343968, 1153.849999500066, 29529.261768455774, 30172.0, 39626.60373396265, 40286.79856600713, 40374.0, 39946.0, 40250.19499610006, 40304.19391612166, 40335.0, 40419.39370909439, 7102.360787529121, 7444.813879653011, 7577.128630207514, 7600.391968642523, 7664.65509052094, 7401.0748656417945, 7503.0, 7520.059992500937, 7525.66134523945, 7555.395568354518, 7481.438892083088, 7522.285382888617, 7526.36025937796, 7543.811404714871, 7573.356264717478, 7412.629368531579, 7464.141623713288, 7464.216257292987, 7474.103107627066, 7481.177070522265, 7381.9630901845685, 7455.366293865028, 7458.0, 7462.179160292363, 7532.983047288655, 36613.450798238046, 36786.8160659195, 36904.0, 36955.63044369549, 37020.074498137765, 3483.442649176145, 3531.611522732497, 3532.4171511700797, 3545.290941811648, 3549.5208146900095, 29666.0, 29690.0, 29733.256668583243, 29740.70259297401, 29752.256193595116, 4947.703137811743, 4977.850664480072, 4999.8000079996755, 5045.243213517965, 5063.367079115111, 191110.0, 191655.0, 192422.9999999986, 192654.0, 194500.0, 47079.29381059287, 47173.0, 47210.76394618005, 47236.29145562819, 47271.5272847274, 48875.0, 49033.75483122633, 49391.75304123528, 49404.752976235606, 49423.25865112026, 16327.102009389597, 16471.0, 16537.83462165387, 16556.6688666226, 16592.585185370463, 12039.518419263131, 12332.075094367958, 13513.0, 13821.101628394323, 13897.16617002983, 14180.716385672435, 14200.218987955555, 14259.42962281497, 14326.355314010894, 14443.277836108153, 5586.581006424534, 5839.211706419664, 5849.532037436995, 5861.912071318934, 5904.291485021825, 1012.5899010900597, 1017.2574020964737, 1026.3226270661328, 1026.6714651311509, 1029.1509504658686, 22201.555968880533, 22615.208467703553, 22889.427764306027, 22892.771072289397, 22911.427214319774, 1116.0, 1128.4019469681082, 1136.9260998035102, 1145.5818626201376, 1153.411760002405, 11799.646010619696, 12178.512859485698, 12193.39033048344, 12198.14612977083, 12243.450463275036, 217398.0, 218923.0, 219856.0, 220255.0, 221105.0, 49396.0, 49614.25578616252, 49624.50375496271, 49844.75077624661, 50015.24977125346, 1435.5836807325759, 1474.8893832962356, 1477.128494188434, 1503.6015455904194, 1507.2539093148741, 21761.238356658076, 22160.445988850406, 22250.331225157854, 22255.221067263217, 22398.66402004004, 35012.77455289053, 35039.47440788441, 35340.0, 35393.469097964065, 35417.0, 189027.0, 190094.0, 190258.0, 190332.0, 190342.0, 29423.558646620746, 30039.849800750857, 32319.838400807846, 32505.512417314232, 32646.34707305888, 39722.40416393814, 39924.60075399209, 40011.399829002, 40088.799556002035, 40390.79804600958, 30821.537676935313, 30897.22756931005, 31026.0, 31078.844605776827, 31262.37475250528, 30479.847600761856, 30824.0, 30852.382952341275, 30852.69147308588, 30911.2272193197, 32846.835765821015, 33317.50023749597, 33345.0, 33605.495917560766, 33627.83186084054, 29049.12852614425, 29109.7089029107, 29659.703402965693, 29667.85166074156, 29694.0, 22653.0, 22753.0, 22832.31503054911, 22865.31404057881, 23228.0], "value": 16686.8858417977, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [551.4457969740411, 555.1617058242053, 565.1240577105485, 575.9510441612463, 580.489169530813, 21098.15607375705, 21809.890950545225, 21842.235521756742, 21847.890760546175, 21872.01575929083, 24576.38559036025, 25682.74317256827, 26806.06178783743, 26853.731462685366, 26928.865355673242, 6370.904436433457, 6466.612003279794, 6468.773592924249, 6522.967385163068, 6527.967360163192, 6116.113163591299, 6316.494680425555, 6422.935770642281, 6433.0, 6454.322296158883, 6417.197850268716, 6419.518536109787, 6423.325550817167, 6426.0, 6427.453666438359, 6412.102305677209, 6421.775237866699, 6432.2602900666325, 6433.420992110709, 6435.6782160892235, 5988.0, 5988.9101663475085, 5990.820275391745, 5992.250968628921, 5995.400459954013, 28806.567901481496, 29613.111606651833, 30178.396432071237, 30997.535036974463, 31150.0, 1933.5939452714947, 1941.5049162463622, 1945.338584881133, 1948.7369205157263, 1958.0503455823914, 18810.435686929413, 19671.409857704293, 19696.507587310432, 19769.11039003248, 19775.604487910306, 2352.6118190498546, 2367.573836709392, 2378.0851959546208, 2390.9521809563885, 2451.031842422251, 136133.3193334037, 138465.307673462, 139531.0, 139777.0, 139971.0, 13605.863941360658, 14748.18884961316, 14816.925915370353, 14866.182359970306, 15055.53722643798, 22932.738699371694, 23201.419964501023, 23229.0, 23283.18508852182, 23797.524049518914, 10357.326773759682, 10629.149668026694, 10683.83974240387, 10713.571457141783, 10765.3540787553, 10200.23498237635, 10209.591616335412, 10256.230782691327, 10342.586296548054, 10386.688399348031, 9188.816223675489, 9305.25557955362, 9368.95315523418, 9379.155875971195, 9694.127528522326, 2613.4381108061857, 2613.5756012972597, 2620.908268210642, 2656.136755554414, 2738.069056520753, 943.6366998705548, 976.5947131940165, 979.9412035277907, 984.3159004491953, 987.2003677021636, 19889.701654475193, 19987.0, 20403.285884993955, 20673.896630516752, 20934.057967391505, 1048.8688913885765, 1055.8099542082346, 1057.2229411382627, 1062.4846949229618, 1062.5643486170634, 10874.130069594414, 11321.547138114547, 13527.729445411234, 14136.08115472491, 14489.78265326021, 550362.992740143, 554889.0, 555867.0, 557070.0, 557934.0, 116259.41870290594, 118324.40837795756, 119472.0, 120116.39941800234, 120429.0, 1270.0791925853682, 1288.9419976101099, 1314.842218933715, 1322.5172811923771, 1335.8396992360979, 21335.78664213338, 21498.570028599654, 21692.0, 21702.78297217071, 21954.89022554877, 57851.0, 58769.412305876394, 59122.40877591341, 60280.698596506736, 61759.691201543705, 67395.0, 67935.66032169807, 68213.0, 68258.65870670615, 68968.31031689618, 14505.854941450873, 14960.775588366401, 15025.248737563297, 15036.173010484528, 15127.16800575935, 23857.284281471584, 23928.641070384307, 23995.16016939399, 24063.639045414682, 24276.0, 18853.24587016504, 18982.810171898105, 19156.371708404942, 19205.615887681888, 19322.516937076685, 16665.0, 17070.658586828442, 17300.788944773743, 17486.0, 17503.29986800514, 18173.90913045479, 19403.708944366128, 19449.222031119163, 19671.901640491706, 19696.704549432056, 14708.41166353334, 14924.552263432115, 14937.626559336104, 14948.0, 14962.177080260255, 18170.636587268444, 18443.07784610737, 18582.44252672422, 18583.721244181077, 18633.161507732457], "value": 12246.92736760362, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1327.820744199533, 1370.3696299702137, 1408.8802451791598, 1411.9929400352996, 1414.4130185972822, 13921.930390348047, 14102.435902563886, 14520.41918323267, 14560.781588276184, 14585.416583336653, 14530.636734081654, 14764.926175369108, 14805.55583332499, 14816.777748333783, 14827.55517334479, 2646.0209722402737, 2689.663792025997, 2693.4209145033815, 2706.174616741893, 2708.2958430807935, 2586.379268975442, 2683.4633073385303, 2795.51078561252, 2798.790090743192, 2800.635917330744, 2710.891564337434, 2716.0, 2745.752882240598, 2746.258510202244, 2746.3408781892404, 2658.3354161459633, 2680.8123431359813, 2701.770349520293, 2702.6486556747695, 2706.134037108126, 2580.1743442098536, 2594.0531705927388, 2613.6732908386452, 2614.307208589725, 2633.3416645838543, 13924.860751392458, 13994.510192143227, 14019.579412617637, 14248.287585620781, 14250.073745206528, 7433.331000209953, 7651.120121186079, 7664.348530374925, 7678.808029799244, 7726.420518461082, 4941.357623508957, 5107.693538387709, 5228.215767634847, 5391.353037635509, 5425.782968681248, 1673.9246733897003, 1691.2981112838158, 1691.621328617173, 1758.2000189913686, 1760.6302676437963, 23808.523829523314, 23896.641550376586, 24009.63985540201, 24052.75947240523, 24060.639090413482, 6537.542372033912, 6900.723971041202, 7212.567245965259, 7261.41908647307, 7318.780436586911, 16657.750133748003, 16802.915985419993, 16817.243224054946, 16822.411215607393, 16834.24245908937, 7849.843003140019, 7951.562664053533, 8330.208630180123, 8336.749897503085, 8347.04009038956, 7351.081114860643, 7457.47797654159, 7468.514546554566, 7475.327220550068, 7485.663145158481, 5032.521910418504, 5153.639245252796, 5169.638125331266, 5259.579233661298, 5260.553347829377, 1442.69703362293, 1467.149053548939, 1480.570634516, 1494.9177795221367, 1513.4173343262923, 845.1675100026468, 861.67687117331, 871.0288028847916, 874.3136637739472, 887.7425546591545, 6915.273896240923, 7010.894836577456, 7010.929890701028, 7044.92955070453, 7047.929520704727, 730.1894896664697, 731.2614259597739, 734.1997223026926, 738.7672883041824, 741.4957828676444, 4777.761111944389, 4945.975270123627, 5127.359080114986, 5182.8963420732125, 5342.519173274424, 19430.805691943184, 20093.49766255855, 20390.69413958792, 20409.081591328424, 20481.38555843327, 19016.904915475334, 19460.4161875144, 19478.22087116528, 19737.013149342474, 20037.198512059353, 11042.33745975244, 11128.721781955353, 11140.610078647209, 11191.104711623051, 11197.104231661448, 7668.357530173123, 7885.132635410215, 7932.484388514728, 7957.56233407145, 8020.478668886503, 16720.498385048468, 16833.326666933186, 16871.072091035112, 16919.15404229759, 17056.31774728997, 17170.484885453458, 17243.310267589157, 17313.74029389535, 17376.131193440026, 17406.47780566585, 10593.788124237626, 10594.15246780271, 10672.8932710675, 10732.892671073188, 11346.149038821955, 13334.26661533625, 13342.06605537603, 13365.799513007507, 13438.529651462542, 13503.39234734459, 10699.572017119539, 10723.410212438392, 10814.89185108139, 10832.729181770519, 10976.396298203676, 10125.898741012496, 10201.897981020094, 10229.74425639365, 10501.31741436804, 10540.84188737185, 9555.665551705657, 9619.903800961902, 9637.662681806103, 9763.1701305387, 9801.215902727907, 6937.132858392701, 7071.964640176766, 7073.575585464889, 7117.8576428470105, 7153.463490238357, 7086.185088714864, 7398.59307738058, 7418.777436676908, 7503.587302698404, 7511.436642251743], "value": 6387.976997912388, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [503.56441677948584, 503.9961827508052, 521.3535216331748, 522.7307936412748, 533.9866503337419, 6109.541784366168, 6134.969325153374, 6211.968940155299, 6237.157983672203, 6276.403741644543, 6038.57729958903, 6079.148919151322, 6114.388561143895, 6177.444030037295, 6213.471854892339, 4796.784144713496, 4858.392700912386, 4954.97522512387, 5042.47054059324, 5054.393472783271, 4909.5581397674205, 4916.262560615901, 4930.926036109461, 4937.481564435719, 4955.355803745526, 4884.829030983917, 4900.411950565937, 4906.1168989581865, 4919.950800491985, 4940.0, 4879.268109783526, 4899.510048995107, 4921.827736029221, 4946.282788995575, 4954.529319714621, 4418.64650827933, 4436.489803672554, 4464.214314274283, 4464.352668863029, 4491.371208030879, 6051.8184454466, 6080.209572755558, 6156.445919867234, 6171.783987560414, 6187.5149964008315, 5584.86037849053, 5596.244506991545, 5601.551875849922, 5603.60774745768, 5616.494515493625, 6524.184476940382, 6554.246261679872, 6556.655885543452, 6558.0, 6567.441767449773, 4822.397200349957, 4825.831095911662, 4827.131116399047, 4856.854294371175, 4861.951380486185, 6573.375529324707, 6662.700178491979, 6752.459803215731, 6757.324267573253, 6876.553024053421, 4815.831445899376, 4856.927146092811, 4880.560749532559, 4898.0, 4937.580305674058, 4923.852284431473, 4924.679895806761, 4959.925601115986, 4970.931249781314, 4991.425986011583, 5545.528630066489, 5545.833624991256, 5550.333959924754, 5550.9192398219375, 5566.4990150886615, 6479.384458476437, 6520.445762110179, 6548.8035358939305, 6562.967185164043, 6633.237177724527, 1712.3236321652878, 1721.4147189955474, 1729.2218501674297, 1736.9305227790999, 1769.9823001770076, 1148.1274231583889, 1191.766521650088, 1205.9035277177804, 1218.482145088334, 1219.9817002744967, 5715.571332150105, 5719.456651618089, 5739.225204597409, 5765.1928729977835, 5780.312154848016, 6562.770303039466, 6579.374959378852, 6596.229583574814, 6663.733450661919, 6670.899936500957, 5723.885522289531, 5742.397048309951, 5752.395998420189, 5766.192733017381, 5771.134329850472, 3527.3650742866016, 3546.8226588670977, 3547.6807087362263, 3559.483874838134, 3576.320499105214, 6401.4238718514625, 6488.610683359014, 6504.967475162689, 6531.836704082436, 6538.673066346663, 6879.793606191823, 6892.586444813327, 6893.862122757517, 6946.131733533309, 6968.198657154492, 6495.447886929569, 6569.408753212234, 6581.572197807127, 6596.96701516499, 6616.636085015371, 6765.695543700447, 6790.435021613693, 6792.966035169792, 6801.5238933274195, 6826.317368263134, 6469.0, 6520.413162815369, 6693.933060669331, 6704.6982885769285, 6711.46308295345, 5929.703514824155, 6421.71102300407, 6435.485161187, 6465.0, 6477.676116194266, 4901.58336541398, 4910.656254062182, 4912.680675756064, 4921.63087768426, 4931.186354251508, 6422.743090276336, 6556.770513032021, 6615.6030638161865, 6669.265990842319, 6691.360616648953, 6381.426401215149, 6410.230772307353, 6497.870042599027, 6500.154979852589, 6576.80269591913, 6439.420452159328, 6450.354964503512, 6460.25707043696, 6473.8705225896165, 6567.2447668518735, 6361.332060133805, 6413.877571424889, 6415.422611964946, 6417.903731444126, 6477.740890364333, 6566.277709451861, 6569.4744420447205, 6631.403173714196, 6651.368120028589, 6678.265390807104, 6294.276158241861, 6305.621662700252, 6311.147995020613, 6316.778912738032, 6316.873662526632], "value": 5016.408082947178, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [909.5588639509838, 912.5528491039389, 931.031727003916, 940.1632547033141, 941.7174847545737, 18019.54951126221, 18783.530411739714, 18837.246510139594, 18844.0, 21675.0, 20546.48633784156, 20915.16339346424, 21059.0, 21076.36770896872, 21093.050812713427, 1033.1476531861208, 1054.9947250263738, 1057.5928267616985, 1059.4755595979962, 1061.6708820265703, 1061.9628313009046, 1065.121274948167, 1065.9413732244723, 1066.7119877633033, 1067.236925598197, 990.8464188050827, 1084.5336505302716, 1096.4901320885785, 1096.8848270931558, 1097.8408130821026, 1091.4324551233394, 1091.9781604367909, 1093.3330668292335, 1095.6767753512734, 1097.6926460591046, 789.4000559574707, 789.6288744290174, 790.0795573157264, 790.9485883417559, 797.9082405523382, 19481.0, 19908.601827963503, 20017.29939452127, 20104.29634962784, 20133.798662013338, 20596.485087872774, 20663.483412914647, 20728.170873165054, 21089.789102108934, 21154.365369038955, 16265.268062937197, 17053.0, 17223.56941076483, 17239.22423490946, 17305.48083557483, 3599.6940260077927, 3646.4348026056005, 3647.215848592565, 3647.908802279938, 3650.142216579102, 123101.0, 124237.37881310536, 130385.0, 131768.0, 132576.0, 16581.0, 16643.251053702614, 16674.83325166757, 16700.331986720394, 16823.41118060862, 31460.37079258402, 31559.684403156134, 31825.04524864258, 32143.35713285768, 32145.0, 7762.534247945028, 7861.52830830152, 7940.722074727357, 8134.837303253902, 8818.206361427503, 4179.0, 4270.765107919032, 4271.466066741657, 4276.230278549829, 4295.334223195409, 5027.421846487628, 5061.164907790173, 5144.356955380577, 5158.484151584811, 5171.741412929338, 974.3958745577787, 978.4178413843894, 998.7253505285959, 1002.7092143278513, 1007.0282177698556, 607.9787207447806, 668.2649086005355, 697.5919087333966, 699.9825004374932, 714.8463080437731, 11642.24325418845, 12431.93784031074, 12608.0, 13224.735505290033, 13615.387307571184, 707.2883621635654, 707.3033062433499, 712.9394001509826, 723.2622724820759, 733.3949491669288, 5854.853628659318, 5860.208871802337, 5957.195778569838, 6042.4561789438285, 6229.968850155811, 108307.45846270719, 108686.0, 111316.0, 113036.0, 114384.0, 96075.51962240144, 96546.0, 96748.51625741967, 97741.0, 98413.50793245988, 83049.67801287881, 84954.5752271247, 85928.57035714781, 86145.56927215324, 86155.13844861597, 12226.14416990802, 13146.276954767198, 13157.934210328887, 13192.142510736772, 13258.602241932758, 38153.427698585096, 38185.0, 39165.216695666495, 39792.0, 39951.80024099861, 70070.29929700842, 70415.0, 70795.0, 72784.0, 77017.22982770325, 18553.53616159607, 19545.0, 19620.705689414386, 19622.509437263612, 19783.703244451055, 18883.339083132025, 19784.406467805988, 20359.4910127248, 22296.10815567424, 22825.657615135453, 19539.60920781548, 19816.108275127943, 19910.60178796445, 20083.799162008192, 20241.0, 19768.40694779159, 20047.0978805957, 20181.697274541188, 20359.083841226886, 20526.794732052487, 18566.814331857047, 18698.813011869708, 18940.242390304233, 19152.904235478734, 19377.321793737152, 13990.930045349709, 14422.783658245344, 15032.924835375754, 15047.699046018799, 15373.92382533212, 14810.703785924437, 14931.328090235747, 15095.245237738289, 15430.382784688487, 15681.843181568038], "value": 9390.340106392963, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1536.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 109.0, 100.0, 107.0, 108.0, 119.0, 107.0, 99.0, 110.0, 112.0], "value": 107.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 120.0, 145.0, 172.0, 122.0, 144.0, 146.0, 120.0, 171.0, 119.0], "value": 140.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 163.0, 163.0, 163.0, 162.0, 163.0, 164.0, 165.0, 170.0, 168.0], "value": 164.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 89.0, 77.0, 172.0, 75.0, 81.0, 76.0, 76.0, 157.0, 76.0], "value": 95.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 133.0, 79.0, 80.0, 81.0, 132.0, 79.0, 78.0, 130.0], "value": 95.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 115.0, 114.0, 115.0, 114.0, 114.0, 114.0, 114.0, 115.0], "value": 114.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 152.0, 150.0, 150.0, 151.0, 150.0, 152.0, 151.0, 153.0, 151.0], "value": 151.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 94.0, 94.0, 93.0, 95.0, 95.0, 94.0, 95.0], "value": 94.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 62.0, 54.0, 55.0, 54.0, 64.0, 53.0, 54.0, 54.0, 53.0], "value": 55.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 55.0, 54.0, 55.0, 55.0, 55.0, 55.0, 55.0, 55.0], "value": 54.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 81.0, 83.0, 83.0, 86.0, 80.0, 79.0, 78.0, 88.0, 79.0], "value": 81.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 131.0, 132.0, 128.0, 129.0, 127.0, 124.0, 128.0, 126.0, 168.0], "value": 132.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 180.0, 177.0, 174.0, 184.0, 184.0, 178.0, 176.0, 179.0, 179.0], "value": 179.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 71.0, 73.0, 68.0, 67.0, 69.0, 68.0, 67.0, 69.0], "value": 68.9, "unit": "score"}]}]} 18:41:03 INFO - Return code: 0 18:41:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:41:03 INFO - # TBPL SUCCESS # 18:41:03 INFO - Running post-action listener: _package_coverage_data 18:41:03 INFO - Running post-action listener: _resource_record_post_action 18:41:03 INFO - [mozharness: 2017-08-15 01:41:03.176429Z] Finished run-tests step (success) 18:41:03 INFO - Running post-run listener: _resource_record_post_run 18:41:03 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 4096; Write bytes: 435490816; Read time: 12; Write time: 340012 18:41:03 INFO - TinderboxPrint: CPU usage
13.1% 18:41:03 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 18:41:03 INFO - TinderboxPrint: I/O write bytes / time
435,490,816 / 340,012 18:41:03 INFO - TinderboxPrint: CPU idle
8,252.6 (86.9%) 18:41:03 INFO - TinderboxPrint: CPU user
1,200.2 (12.6%) 18:41:03 INFO - TinderboxPrint: Swap in / out
0 / 0 18:41:03 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 106541056; Read time: 0; Write time: 101548 18:41:03 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 18:41:03 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 328343552; Read time: 0; Write time: 238436 18:41:03 INFO - Running post-run listener: _upload_blobber_files 18:41:03 INFO - Blob upload gear active. 18:41:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:41:03 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:41:03 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:41:03 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:41:04 INFO - (blobuploader) - INFO - Open directory for files ... 18:41:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:41:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:41:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:41:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:41:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:41:05 INFO - (blobuploader) - INFO - Done attempting. 18:41:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:41:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:41:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:41:06 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:41:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:41:06 INFO - (blobuploader) - INFO - Done attempting. 18:41:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:41:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:41:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:41:14 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:41:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:41:14 INFO - (blobuploader) - INFO - Done attempting. 18:41:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:41:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:41:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:41:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:41:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:41:15 INFO - (blobuploader) - INFO - Done attempting. 18:41:15 INFO - (blobuploader) - INFO - Iteration through files over. 18:41:15 INFO - Return code: 0 18:41:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:41:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:41:15 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5ced9a38c6f3bb0ee17d36f8ae68c2ed0541639b556d0dd59488aa4438a505e84f1c8fb53bb449d0cbe0ea6c717134f987fd67892f8a6489ac4bfa5bb2992c7d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/59a46d4b8887f74f8f97a61a19bb67e40bd0f89168b68fc2a959dc6a21f54ff7a1ab20dcf315e1efcc51f4cbf965cf25ad01618be5c938b2ccc0af36023a83a1", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2e4c40931b69f569c8b339128b549d4d35e639586f56ab2e502c8fb17c5da70e0af8617a0a200b7a37773e42c69a62de8fb9d00255ffbf027419f0ab83c412dc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2f4a30aa9686b21e1210224dc114e2a4a8547dcc24e68fc4ff5b6925677fa2df44840e9d22c4f66edfa01fcda03d41da179c8f7971f1f51746f8a1cee4b13dc7"} 18:41:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:41:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:41:15 INFO - Contents: 18:41:15 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5ced9a38c6f3bb0ee17d36f8ae68c2ed0541639b556d0dd59488aa4438a505e84f1c8fb53bb449d0cbe0ea6c717134f987fd67892f8a6489ac4bfa5bb2992c7d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/59a46d4b8887f74f8f97a61a19bb67e40bd0f89168b68fc2a959dc6a21f54ff7a1ab20dcf315e1efcc51f4cbf965cf25ad01618be5c938b2ccc0af36023a83a1", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2e4c40931b69f569c8b339128b549d4d35e639586f56ab2e502c8fb17c5da70e0af8617a0a200b7a37773e42c69a62de8fb9d00255ffbf027419f0ab83c412dc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2f4a30aa9686b21e1210224dc114e2a4a8547dcc24e68fc4ff5b6925677fa2df44840e9d22c4f66edfa01fcda03d41da179c8f7971f1f51746f8a1cee4b13dc7"} 18:41:15 INFO - Running post-run listener: copy_logs_to_upload_dir 18:41:15 INFO - Copying logs to upload dir... 18:41:15 INFO - mkdir: /builds/slave/test/build/upload/logs 18:41:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.174744 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2017-08-14 18:41:13.722226) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-14 18:41:13.728386) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1502759692.568319-793261140 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5ced9a38c6f3bb0ee17d36f8ae68c2ed0541639b556d0dd59488aa4438a505e84f1c8fb53bb449d0cbe0ea6c717134f987fd67892f8a6489ac4bfa5bb2992c7d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/59a46d4b8887f74f8f97a61a19bb67e40bd0f89168b68fc2a959dc6a21f54ff7a1ab20dcf315e1efcc51f4cbf965cf25ad01618be5c938b2ccc0af36023a83a1", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2e4c40931b69f569c8b339128b549d4d35e639586f56ab2e502c8fb17c5da70e0af8617a0a200b7a37773e42c69a62de8fb9d00255ffbf027419f0ab83c412dc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2f4a30aa9686b21e1210224dc114e2a4a8547dcc24e68fc4ff5b6925677fa2df44840e9d22c4f66edfa01fcda03d41da179c8f7971f1f51746f8a1cee4b13dc7"} build_url:https://queue.taskcluster.net/v1/task/QxuElLloREiYtzE68JGu4g/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011341 build_url: 'https://queue.taskcluster.net/v1/task/QxuElLloREiYtzE68JGu4g/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5ced9a38c6f3bb0ee17d36f8ae68c2ed0541639b556d0dd59488aa4438a505e84f1c8fb53bb449d0cbe0ea6c717134f987fd67892f8a6489ac4bfa5bb2992c7d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/59a46d4b8887f74f8f97a61a19bb67e40bd0f89168b68fc2a959dc6a21f54ff7a1ab20dcf315e1efcc51f4cbf965cf25ad01618be5c938b2ccc0af36023a83a1", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2e4c40931b69f569c8b339128b549d4d35e639586f56ab2e502c8fb17c5da70e0af8617a0a200b7a37773e42c69a62de8fb9d00255ffbf027419f0ab83c412dc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2f4a30aa9686b21e1210224dc114e2a4a8547dcc24e68fc4ff5b6925677fa2df44840e9d22c4f66edfa01fcda03d41da179c8f7971f1f51746f8a1cee4b13dc7"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-14 18:41:13.763321) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-14 18:41:13.763918) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1502759692.568319-793261140 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004408 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-14 18:41:13.844844) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-14 18:41:13.845188) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-14 18:41:13.845561) ========= ========= Total master_lag: 0.20 =========