builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-031 starttime: 1493871694.48 results: success (0) revision: d19472763d1dbc039a14260444781308b4e97f29 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-03 21:21:34.482115) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-03 21:21:34.482515) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-03 21:21:34.482799) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-03 21:21:34.534174) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-03 21:21:34.534518) ========= 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=db0483c52d987ecb441a263b000001ad-1493868486.264432-1475407895 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-05-03 21:21:34-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 226M=0s 2017-05-03 21:21:34 (226 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.129283 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-03 21:21:34.687913) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-03 21:21:34.688291) ========= 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=db0483c52d987ecb441a263b000001ad-1493868486.264432-1475407895 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021249 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-03 21:21:34.737480) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-05-03 21:21:34.739383) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d19472763d1dbc039a14260444781308b4e97f29 --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 d19472763d1dbc039a14260444781308b4e97f29 --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=db0483c52d987ecb441a263b000001ad-1493868486.264432-1475407895 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-05-03 21:21:34,873 truncating revision to first 12 chars 2017-05-03 21:21:34,873 Setting DEBUG logging. 2017-05-03 21:21:34,873 attempt 1/10 2017-05-03 21:21:34,873 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d19472763d1d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-03 21:21:38,156 unpacking tar archive at: mozilla-central-d19472763d1d/testing/mozharness/ program finished with exit code 0 elapsedTime=3.617023 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-05-03 21:21:38.373168) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-03 21:21:38.373474) ========= script_repo_revision: d19472763d1dbc039a14260444781308b4e97f29 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-03 21:21:38.377850) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-03 21:21:38.378129) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-03 21:21:38.388545) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2017-05-03 21:21:38.388853) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=db0483c52d987ecb441a263b000001ad-1493868486.264432-1475407895 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:21:38 INFO - MultiFileLogger online at 20170503 21:21:38 in /builds/slave/test 21:21:38 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 21:21:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:21:38 INFO - {'append_to_log': False, 21:21:38 INFO - 'base_work_dir': '/builds/slave/test', 21:21:38 INFO - 'blob_upload_branch': 'Firefox', 21:21:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:21:38 INFO - 'branch': 'Firefox', 21:21:38 INFO - 'buildbot_json_path': 'buildprops.json', 21:21:38 INFO - 'config_files': ('talos/linux_config.py',), 21:21:38 INFO - 'default_actions': ('clobber', 21:21:38 INFO - 'read-buildbot-config', 21:21:38 INFO - 'download-and-extract', 21:21:38 INFO - 'populate-webroot', 21:21:38 INFO - 'create-virtualenv', 21:21:38 INFO - 'install', 21:21:38 INFO - 'run-tests'), 21:21:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:21:38 INFO - 'download_minidump_stackwalk': True, 21:21:38 INFO - 'download_symbols': 'ondemand', 21:21:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:21:38 INFO - 'tooltool.py': '/tools/tooltool.py', 21:21:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:21:38 INFO - '/tools/misc-python/virtualenv.py')}, 21:21:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:21:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:21:38 INFO - 'gecko_profile': False, 21:21:38 INFO - 'gecko_profile_interval': 0, 21:21:38 INFO - 'installer_path': 'installer.exe', 21:21:38 INFO - 'log_level': 'info', 21:21:38 INFO - 'log_name': 'talos', 21:21:38 INFO - 'log_to_console': True, 21:21:38 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:21:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:21:38 INFO - 'opt_config_files': (), 21:21:38 INFO - 'pip_index': False, 21:21:38 INFO - 'suite': 'dromaeojs-e10s', 21:21:38 INFO - 'system_bits': '32', 21:21:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:21:38 INFO - 'title': 'talos-linux64-ix-031', 21:21:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:21:38 INFO - 'use_talos_json': True, 21:21:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:21:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:21:38 INFO - 'work_dir': 'build'} 21:21:38 INFO - [mozharness: 2017-05-04 04:21:38.630926Z] Running clobber step. 21:21:38 INFO - Running pre-action listener: _resource_record_pre_action 21:21:38 INFO - Running main action method: clobber 21:21:38 INFO - rmtree: /builds/slave/test/build 21:21:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:21:39 INFO - Running post-action listener: _resource_record_post_action 21:21:39 INFO - [mozharness: 2017-05-04 04:21:39.693286Z] Finished clobber step (success) 21:21:39 INFO - [mozharness: 2017-05-04 04:21:39.693400Z] Running read-buildbot-config step. 21:21:39 INFO - Running pre-action listener: _resource_record_pre_action 21:21:39 INFO - Running main action method: read_buildbot_config 21:21:39 INFO - Using buildbot properties: 21:21:39 INFO - { 21:21:39 INFO - "project": "", 21:21:39 INFO - "product": "firefox", 21:21:39 INFO - "who": "ihsiao@mozilla.com", 21:21:39 INFO - "installer_path": "public/build/target.tar.bz2", 21:21:39 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 21:21:39 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 21:21:39 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 21:21:39 INFO - "stage_platform": "linux64-pgo", 21:21:39 INFO - "basedir": "/builds/slave/test", 21:21:39 INFO - "buildnumber": 193, 21:21:39 INFO - "platform": "ubuntu64_hw", 21:21:39 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 21:21:39 INFO - "slavebuilddir": "test", 21:21:39 INFO - "taskId": "eck7fghUQhOcIHwhsfkVdA", 21:21:39 INFO - "branch": "mozilla-central", 21:21:39 INFO - "script_repo_revision": "production", 21:21:39 INFO - "revision": "d19472763d1dbc039a14260444781308b4e97f29", 21:21:39 INFO - "slavename": "talos-linux64-ix-031", 21:21:39 INFO - "repo_path": "mozilla-central" 21:21:39 INFO - } 21:21:39 INFO - Finding installer, test and symbols from parent task. 21:21:39 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eck7fghUQhOcIHwhsfkVdA'}, attempt #1 21:21:40 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eck7fghUQhOcIHwhsfkVdA'}, attempt #1 21:21:40 INFO - Task dependencies: c-MWHmbCRDKsZJWYrhhqaQ 21:21:40 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/c-MWHmbCRDKsZJWYrhhqaQ'}, attempt #1 21:21:41 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/c-MWHmbCRDKsZJWYrhhqaQ/artifacts/public/build/target.tar.bz2 21:21:41 INFO - Set test_url: https://queue.taskcluster.net/v1/task/c-MWHmbCRDKsZJWYrhhqaQ/artifacts/public/build/test_packages.json 21:21:41 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/c-MWHmbCRDKsZJWYrhhqaQ/artifacts/public/build/target.crashreporter-symbols.zip 21:21:41 INFO - Running post-action listener: _resource_record_post_action 21:21:41 INFO - [mozharness: 2017-05-04 04:21:41.035410Z] Finished read-buildbot-config step (success) 21:21:41 INFO - [mozharness: 2017-05-04 04:21:41.035626Z] Running download-and-extract step. 21:21:41 INFO - Running pre-action listener: _resource_record_pre_action 21:21:41 INFO - Running main action method: download_and_extract 21:21:41 INFO - mkdir: /builds/slave/test/build/tests 21:21:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 21:21:41 INFO - trying https://queue.taskcluster.net/v1/task/c-MWHmbCRDKsZJWYrhhqaQ/artifacts/public/build/target.test_packages.json 21:21:41 INFO - Downloading https://queue.taskcluster.net/v1/task/c-MWHmbCRDKsZJWYrhhqaQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 21:21:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/c-MWHmbCRDKsZJWYrhhqaQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 21:21:41 INFO - Downloaded 986 bytes. 21:21:41 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 21:21:41 INFO - Using the following test package requirements: 21:21:41 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 21:21:41 INFO - u'common': [u'target.common.tests.zip'], 21:21:41 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 21:21:41 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 21:21:41 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 21:21:41 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 21:21:41 INFO - u'mozbase': [u'target.common.tests.zip'], 21:21:41 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 21:21:41 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 21:21:41 INFO - u'web-platform': [u'target.common.tests.zip', 21:21:41 INFO - u'target.web-platform.tests.zip'], 21:21:41 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 21:21:41 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 21:21:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/c-MWHmbCRDKsZJWYrhhqaQ/artifacts/public/build/target.common.tests.zip 21:21:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/c-MWHmbCRDKsZJWYrhhqaQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 21:21:41 INFO - Fetch https://queue.taskcluster.net/v1/task/c-MWHmbCRDKsZJWYrhhqaQ/artifacts/public/build/target.common.tests.zip into memory 21:21:42 INFO - Content-Length response header: 60556777 21:21:42 INFO - Bytes received: 60556777 21:21:52 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 21:21:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/c-MWHmbCRDKsZJWYrhhqaQ/artifacts/public/build/target.common.tests.zip 21:21:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/c-MWHmbCRDKsZJWYrhhqaQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 21:21:52 INFO - Fetch https://queue.taskcluster.net/v1/task/c-MWHmbCRDKsZJWYrhhqaQ/artifacts/public/build/target.common.tests.zip into memory 21:21:53 INFO - Content-Length response header: 60556777 21:21:53 INFO - Bytes received: 60556777 21:22:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/c-MWHmbCRDKsZJWYrhhqaQ/artifacts/public/build/target.talos.tests.zip 21:22:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/c-MWHmbCRDKsZJWYrhhqaQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 21:22:04 INFO - Fetch https://queue.taskcluster.net/v1/task/c-MWHmbCRDKsZJWYrhhqaQ/artifacts/public/build/target.talos.tests.zip into memory 21:22:05 INFO - Content-Length response header: 14408809 21:22:05 INFO - Bytes received: 14408809 21:22:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 21:22:05 INFO - trying https://queue.taskcluster.net/v1/task/c-MWHmbCRDKsZJWYrhhqaQ/artifacts/public/build/target.tar.bz2 21:22:05 INFO - Downloading https://queue.taskcluster.net/v1/task/c-MWHmbCRDKsZJWYrhhqaQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 21:22:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/c-MWHmbCRDKsZJWYrhhqaQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 21:22:06 INFO - Downloaded 64061847 bytes. 21:22:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/c-MWHmbCRDKsZJWYrhhqaQ/artifacts/public/build/target.tar.bz2 21:22:06 INFO - mkdir: /builds/slave/test/properties 21:22:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:22:06 INFO - Writing to file /builds/slave/test/properties/build_url 21:22:06 INFO - Contents: 21:22:06 INFO - build_url:https://queue.taskcluster.net/v1/task/c-MWHmbCRDKsZJWYrhhqaQ/artifacts/public/build/target.tar.bz2 21:22:06 INFO - Running post-action listener: _resource_record_post_action 21:22:06 INFO - Running post-action listener: set_extra_try_arguments 21:22:06 INFO - [mozharness: 2017-05-04 04:22:06.615713Z] Finished download-and-extract step (success) 21:22:06 INFO - [mozharness: 2017-05-04 04:22:06.615810Z] Running populate-webroot step. 21:22:06 INFO - Running pre-action listener: _resource_record_pre_action 21:22:06 INFO - Running main action method: populate_webroot 21:22:06 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:22:06 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:22:06 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:22:06 INFO - '--tppagecycles', 21:22:06 INFO - '7'], 21:22:06 INFO - 'tests': ['tsvgm']}}, 21:22:06 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:22:06 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:22:06 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:22:06 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:22:06 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:22:06 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:22:06 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 21:22:06 INFO - 'talos_options': ['--disable-e10s'], 21:22:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:22:06 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 21:22:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:22:06 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 21:22:06 INFO - 'talos_options': ['--disable-e10s'], 21:22:06 INFO - 'tests': ['damp', 'tps']}, 21:22:06 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 21:22:06 INFO - 'tests': ['damp', 'tps']}, 21:22:06 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:22:06 INFO - 'tests': ['dromaeo_dom']}, 21:22:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:22:06 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:22:06 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 21:22:06 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 21:22:06 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:22:06 INFO - 'tests': ['a11yr', 21:22:06 INFO - 'ts_paint', 21:22:06 INFO - 'tpaint', 21:22:06 INFO - 'sessionrestore', 21:22:06 INFO - 'sessionrestore_no_auto_restore', 21:22:06 INFO - 'tabpaint']}, 21:22:06 INFO - 'other-e10s': {'tests': ['a11yr', 21:22:06 INFO - 'ts_paint', 21:22:06 INFO - 'tpaint', 21:22:06 INFO - 'sessionrestore', 21:22:06 INFO - 'sessionrestore_no_auto_restore', 21:22:06 INFO - 'tabpaint']}, 21:22:06 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:22:06 INFO - 'ts_paint', 21:22:06 INFO - 'tpaint', 21:22:06 INFO - 'sessionrestore', 21:22:06 INFO - 'sessionrestore_no_auto_restore', 21:22:06 INFO - 'tabpaint']}, 21:22:06 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:22:06 INFO - 'ts_paint', 21:22:06 INFO - 'tpaint', 21:22:06 INFO - 'sessionrestore', 21:22:06 INFO - 'sessionrestore_no_auto_restore', 21:22:06 INFO - 'tabpaint']}, 21:22:06 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:22:06 INFO - 'tests': ['a11yr', 21:22:06 INFO - 'ts_paint', 21:22:06 INFO - 'tpaint', 21:22:06 INFO - 'sessionrestore', 21:22:06 INFO - 'sessionrestore_no_auto_restore', 21:22:06 INFO - 'tabpaint']}, 21:22:06 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:22:06 INFO - 'tests': ['a11yr', 21:22:06 INFO - 'ts_paint', 21:22:06 INFO - 'tpaint', 21:22:06 INFO - 'sessionrestore', 21:22:06 INFO - 'sessionrestore_no_auto_restore', 21:22:06 INFO - 'tabpaint']}, 21:22:06 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 21:22:06 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 21:22:06 INFO - 'bloom_basic_ref']}, 21:22:06 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:22:06 INFO - 'tests': ['tsvgx', 21:22:06 INFO - 'tsvgr_opacity', 21:22:06 INFO - 'tart', 21:22:06 INFO - 'tscrollx', 21:22:06 INFO - 'cart', 21:22:06 INFO - 'tsvg_static']}, 21:22:06 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:22:06 INFO - 'tsvgr_opacity', 21:22:06 INFO - 'tart', 21:22:06 INFO - 'tscrollx', 21:22:06 INFO - 'cart', 21:22:06 INFO - 'tsvg_static']}, 21:22:06 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 21:22:06 INFO - 'talos_options': ['--disable-e10s'], 21:22:06 INFO - 'tests': ['tp5o']}, 21:22:06 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 21:22:06 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 21:22:06 INFO - 'talos_options': ['--disable-e10s', 21:22:06 INFO - '--xperf_path', 21:22:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:22:06 INFO - 'tests': ['tp5n']}, 21:22:06 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 21:22:06 INFO - 'talos_options': ['--xperf_path', 21:22:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:22:06 INFO - 'tests': ['tp5n']}}, 21:22:06 INFO - 'talos.zip': {'path': '', 21:22:06 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:22:06 INFO - Running post-action listener: _resource_record_post_action 21:22:06 INFO - [mozharness: 2017-05-04 04:22:06.629598Z] Finished populate-webroot step (success) 21:22:06 INFO - [mozharness: 2017-05-04 04:22:06.629687Z] Running create-virtualenv step. 21:22:06 INFO - Running pre-action listener: _resource_record_pre_action 21:22:06 INFO - Running main action method: create_virtualenv 21:22:06 INFO - Creating virtualenv /builds/slave/test/build/venv 21:22:06 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 21:22:06 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:22:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:22:06 INFO - Using real prefix '/usr' 21:22:06 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:22:07 INFO - Installing distribute.............................................................................................................................................................................................done. 21:22:09 INFO - Installing pip.................done. 21:22:09 INFO - Return code: 0 21:22:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 21:22:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 21:22:09 INFO - Reading from file tmpfile_stdout 21:22:09 INFO - Output received: 21:22:09 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) 21:22:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:22:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 21:22:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:22:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:22:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 21:22:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:22:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:22:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0xbe61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcdea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe83b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe81290>, '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 0xe81b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe82040>, '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': 'db0483c52d987ecb441a263b000001ad-1493868486.264432-1475407895', '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 21:22:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 21:22:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 21:22:09 INFO - Using env: {'DISPLAY': ':0', 21:22:09 INFO - 'HOME': '/home/cltbld', 21:22:09 INFO - 'LANG': 'en_US.UTF-8', 21:22:09 INFO - 'LANGUAGE': 'en_US:en', 21:22:09 INFO - 'LOGNAME': 'cltbld', 21:22:09 INFO - 'MAIL': '/var/mail/cltbld', 21:22:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:22:09 INFO - 'MOZ_NO_REMOTE': '1', 21:22:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:22:09 INFO - 'NO_EM_RESTART': '1', 21:22:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:22:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:22:09 INFO - 'PWD': '/builds/slave/test', 21:22:09 INFO - 'SHELL': '/bin/bash', 21:22:09 INFO - 'SHLVL': '1', 21:22:09 INFO - 'TERM': 'linux', 21:22:09 INFO - 'TMOUT': '86400', 21:22:09 INFO - 'USER': 'cltbld', 21:22:09 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1493868486.264432-1475407895', 21:22:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:22:09 INFO - '_': '/tools/buildbot/bin/python'} 21:22:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:22:09 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 21:22:09 INFO - Cleaning up... 21:22:09 INFO - Return code: 0 21:22:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:22:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 21:22:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:22:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:22:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 21:22:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:22:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:22:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0xbe61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcdea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe83b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe81290>, '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 0xe81b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe82040>, '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': 'db0483c52d987ecb441a263b000001ad-1493868486.264432-1475407895', '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 21:22:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 21:22:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 21:22:09 INFO - Using env: {'DISPLAY': ':0', 21:22:09 INFO - 'HOME': '/home/cltbld', 21:22:09 INFO - 'LANG': 'en_US.UTF-8', 21:22:09 INFO - 'LANGUAGE': 'en_US:en', 21:22:09 INFO - 'LOGNAME': 'cltbld', 21:22:09 INFO - 'MAIL': '/var/mail/cltbld', 21:22:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:22:09 INFO - 'MOZ_NO_REMOTE': '1', 21:22:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:22:09 INFO - 'NO_EM_RESTART': '1', 21:22:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:22:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:22:09 INFO - 'PWD': '/builds/slave/test', 21:22:09 INFO - 'SHELL': '/bin/bash', 21:22:09 INFO - 'SHLVL': '1', 21:22:09 INFO - 'TERM': 'linux', 21:22:09 INFO - 'TMOUT': '86400', 21:22:09 INFO - 'USER': 'cltbld', 21:22:09 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1493868486.264432-1475407895', 21:22:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:22:09 INFO - '_': '/tools/buildbot/bin/python'} 21:22:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:22:09 INFO - Downloading/unpacking psutil>=3.1.1 21:22:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:22:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:22:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:22:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:22:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:22:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:22:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:22:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:22:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:22:11 INFO - Installing collected packages: psutil 21:22:11 INFO - Running setup.py install for psutil 21:22:11 INFO - building 'psutil._psutil_linux' extension 21:22:11 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 21:22:11 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 21:22:11 INFO - building 'psutil._psutil_posix' extension 21:22:11 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 21:22:11 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 21:22:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:22:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:22:11 INFO - Successfully installed psutil 21:22:11 INFO - Cleaning up... 21:22:11 INFO - Return code: 0 21:22:11 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:22:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 21:22:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:22:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:22:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 21:22:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:22:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:22:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0xbe61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcdea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe83b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe81290>, '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 0xe81b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe82040>, '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': 'db0483c52d987ecb441a263b000001ad-1493868486.264432-1475407895', '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 21:22:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 21:22:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 21:22:11 INFO - Using env: {'DISPLAY': ':0', 21:22:11 INFO - 'HOME': '/home/cltbld', 21:22:11 INFO - 'LANG': 'en_US.UTF-8', 21:22:11 INFO - 'LANGUAGE': 'en_US:en', 21:22:11 INFO - 'LOGNAME': 'cltbld', 21:22:11 INFO - 'MAIL': '/var/mail/cltbld', 21:22:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:22:11 INFO - 'MOZ_NO_REMOTE': '1', 21:22:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:22:11 INFO - 'NO_EM_RESTART': '1', 21:22:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:22:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:22:11 INFO - 'PWD': '/builds/slave/test', 21:22:11 INFO - 'SHELL': '/bin/bash', 21:22:11 INFO - 'SHLVL': '1', 21:22:11 INFO - 'TERM': 'linux', 21:22:11 INFO - 'TMOUT': '86400', 21:22:11 INFO - 'USER': 'cltbld', 21:22:11 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1493868486.264432-1475407895', 21:22:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:22:11 INFO - '_': '/tools/buildbot/bin/python'} 21:22:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:22:11 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:22:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:22:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:22:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:22:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:22:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:22:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:22:13 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:22:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:22:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:22:13 INFO - Installing collected packages: mozsystemmonitor 21:22:13 INFO - Running setup.py install for mozsystemmonitor 21:22:13 INFO - Successfully installed mozsystemmonitor 21:22:13 INFO - Cleaning up... 21:22:14 INFO - Return code: 0 21:22:14 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:22:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 21:22:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:22:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:22:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 21:22:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:22:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:22:14 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0xbe61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcdea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe83b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe81290>, '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 0xe81b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe82040>, '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': 'db0483c52d987ecb441a263b000001ad-1493868486.264432-1475407895', '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 21:22:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 21:22:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 21:22:14 INFO - Using env: {'DISPLAY': ':0', 21:22:14 INFO - 'HOME': '/home/cltbld', 21:22:14 INFO - 'LANG': 'en_US.UTF-8', 21:22:14 INFO - 'LANGUAGE': 'en_US:en', 21:22:14 INFO - 'LOGNAME': 'cltbld', 21:22:14 INFO - 'MAIL': '/var/mail/cltbld', 21:22:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:22:14 INFO - 'MOZ_NO_REMOTE': '1', 21:22:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:22:14 INFO - 'NO_EM_RESTART': '1', 21:22:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:22:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:22:14 INFO - 'PWD': '/builds/slave/test', 21:22:14 INFO - 'SHELL': '/bin/bash', 21:22:14 INFO - 'SHLVL': '1', 21:22:14 INFO - 'TERM': 'linux', 21:22:14 INFO - 'TMOUT': '86400', 21:22:14 INFO - 'USER': 'cltbld', 21:22:14 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1493868486.264432-1475407895', 21:22:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:22:14 INFO - '_': '/tools/buildbot/bin/python'} 21:22:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:22:14 INFO - Downloading/unpacking jsonschema==2.5.1 21:22:14 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 21:22:14 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 21:22:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:22:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:22:14 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 21:22:14 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 21:22:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:22:15 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:22:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:22:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:22:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:22:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:22:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:22:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:22:15 INFO - Downloading functools32-3.2.3-2.tar.gz 21:22:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:22:15 INFO - warning: no files found matching '*.txt' 21:22:15 INFO - no previously-included directories found matching 'build' 21:22:15 INFO - no previously-included directories found matching 'dist' 21:22:15 INFO - no previously-included directories found matching '.git*' 21:22:15 INFO - Installing collected packages: jsonschema, functools32 21:22:15 INFO - Running setup.py install for functools32 21:22:15 INFO - warning: no files found matching '*.txt' 21:22:15 INFO - no previously-included directories found matching 'build' 21:22:15 INFO - no previously-included directories found matching 'dist' 21:22:15 INFO - no previously-included directories found matching '.git*' 21:22:15 INFO - Successfully installed jsonschema functools32 21:22:15 INFO - Cleaning up... 21:22:15 INFO - Return code: 0 21:22:15 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:22:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 21:22:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:22:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:22:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 21:22:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:22:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:22:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0xbe61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcdea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe83b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe81290>, '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 0xe81b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe82040>, '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': 'db0483c52d987ecb441a263b000001ad-1493868486.264432-1475407895', '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 21:22:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 21:22:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 21:22:15 INFO - Using env: {'DISPLAY': ':0', 21:22:15 INFO - 'HOME': '/home/cltbld', 21:22:15 INFO - 'LANG': 'en_US.UTF-8', 21:22:15 INFO - 'LANGUAGE': 'en_US:en', 21:22:15 INFO - 'LOGNAME': 'cltbld', 21:22:15 INFO - 'MAIL': '/var/mail/cltbld', 21:22:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:22:15 INFO - 'MOZ_NO_REMOTE': '1', 21:22:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:22:15 INFO - 'NO_EM_RESTART': '1', 21:22:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:22:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:22:15 INFO - 'PWD': '/builds/slave/test', 21:22:15 INFO - 'SHELL': '/bin/bash', 21:22:15 INFO - 'SHLVL': '1', 21:22:15 INFO - 'TERM': 'linux', 21:22:15 INFO - 'TMOUT': '86400', 21:22:15 INFO - 'USER': 'cltbld', 21:22:15 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1493868486.264432-1475407895', 21:22:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:22:15 INFO - '_': '/tools/buildbot/bin/python'} 21:22:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:22:15 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:22:15 INFO - Cleaning up... 21:22:15 INFO - Return code: 0 21:22:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:22:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 21:22:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:22:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:22:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 21:22:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:22:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:22:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0xbe61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcdea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe83b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe81290>, '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 0xe81b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe82040>, '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': 'db0483c52d987ecb441a263b000001ad-1493868486.264432-1475407895', '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 21:22:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 21:22:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 21:22:15 INFO - Using env: {'DISPLAY': ':0', 21:22:15 INFO - 'HOME': '/home/cltbld', 21:22:15 INFO - 'LANG': 'en_US.UTF-8', 21:22:15 INFO - 'LANGUAGE': 'en_US:en', 21:22:15 INFO - 'LOGNAME': 'cltbld', 21:22:15 INFO - 'MAIL': '/var/mail/cltbld', 21:22:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:22:15 INFO - 'MOZ_NO_REMOTE': '1', 21:22:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:22:15 INFO - 'NO_EM_RESTART': '1', 21:22:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:22:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:22:15 INFO - 'PWD': '/builds/slave/test', 21:22:15 INFO - 'SHELL': '/bin/bash', 21:22:15 INFO - 'SHLVL': '1', 21:22:15 INFO - 'TERM': 'linux', 21:22:15 INFO - 'TMOUT': '86400', 21:22:15 INFO - 'USER': 'cltbld', 21:22:15 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1493868486.264432-1475407895', 21:22:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:22:15 INFO - '_': '/tools/buildbot/bin/python'} 21:22:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:22:16 INFO - Downloading/unpacking blobuploader==1.2.4 21:22:16 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 21:22:16 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 21:22:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:22:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:22:16 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 21:22:16 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 21:22:17 INFO - Downloading blobuploader-1.2.4.tar.gz 21:22:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:22:17 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:22:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:22:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:22:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:22:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:22:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:22:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:22:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:22:17 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:22:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:22:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:22:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:22:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:22:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:22:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:22:17 INFO - Downloading docopt-0.6.1.tar.gz 21:22:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:22:17 INFO - Installing collected packages: blobuploader, requests, docopt 21:22:17 INFO - Running setup.py install for blobuploader 21:22:17 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:22:17 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:22:17 INFO - Running setup.py install for requests 21:22:18 INFO - Running setup.py install for docopt 21:22:18 INFO - Successfully installed blobuploader requests docopt 21:22:18 INFO - Cleaning up... 21:22:18 INFO - Return code: 0 21:22:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:22:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 21:22:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:22:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:22:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 21:22:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:22:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:22:18 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0xbe61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcdea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe83b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe81290>, '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 0xe81b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe82040>, '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': 'db0483c52d987ecb441a263b000001ad-1493868486.264432-1475407895', '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 21:22:18 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 21:22:18 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:22:18 INFO - Using env: {'DISPLAY': ':0', 21:22:18 INFO - 'HOME': '/home/cltbld', 21:22:18 INFO - 'LANG': 'en_US.UTF-8', 21:22:18 INFO - 'LANGUAGE': 'en_US:en', 21:22:18 INFO - 'LOGNAME': 'cltbld', 21:22:18 INFO - 'MAIL': '/var/mail/cltbld', 21:22:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:22:18 INFO - 'MOZ_NO_REMOTE': '1', 21:22:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:22:18 INFO - 'NO_EM_RESTART': '1', 21:22:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:22:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:22:18 INFO - 'PWD': '/builds/slave/test', 21:22:18 INFO - 'SHELL': '/bin/bash', 21:22:18 INFO - 'SHLVL': '1', 21:22:18 INFO - 'TERM': 'linux', 21:22:18 INFO - 'TMOUT': '86400', 21:22:18 INFO - 'USER': 'cltbld', 21:22:18 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1493868486.264432-1475407895', 21:22:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:22:18 INFO - '_': '/tools/buildbot/bin/python'} 21:22:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:22:18 INFO - Running setup.py (path:/tmp/pip-vKqO0T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:22:18 INFO - Running setup.py (path:/tmp/pip-dwQOfo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:22:18 INFO - Running setup.py (path:/tmp/pip-7Za63B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:22:18 INFO - Running setup.py (path:/tmp/pip-xBUDTY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:22:18 INFO - Running setup.py (path:/tmp/pip-9RCbXZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:22:18 INFO - Running setup.py (path:/tmp/pip-HZb5pN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:22:19 INFO - Running setup.py (path:/tmp/pip-HsWYB1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:22:19 INFO - Running setup.py (path:/tmp/pip-BXNMN0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:22:19 INFO - Running setup.py (path:/tmp/pip-O98_YN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:22:19 INFO - Running setup.py (path:/tmp/pip-jXO2SU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:22:19 INFO - Running setup.py (path:/tmp/pip-XVNycf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:22:19 INFO - Running setup.py (path:/tmp/pip-8ZqFqQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:22:19 INFO - Running setup.py (path:/tmp/pip-p8yiv6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:22:19 INFO - Running setup.py (path:/tmp/pip-jXqY8Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:22:19 INFO - Running setup.py (path:/tmp/pip-EH77Ox-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:22:19 INFO - Running setup.py (path:/tmp/pip-LqDX8p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:22:19 INFO - Running setup.py (path:/tmp/pip-AerWMH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:22:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:22:19 INFO - Running setup.py install for manifestparser 21:22:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:22:19 INFO - Running setup.py install for mozcrash 21:22:20 INFO - Running setup.py install for mozdebug 21:22:20 INFO - Running setup.py install for mozdevice 21:22:20 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:22:20 INFO - Running setup.py install for mozfile 21:22:20 INFO - Running setup.py install for mozhttpd 21:22:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:22:20 INFO - Running setup.py install for mozinfo 21:22:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:22:20 INFO - Running setup.py install for mozInstall 21:22:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:22:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:22:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:22:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:22:20 INFO - Running setup.py install for mozleak 21:22:20 INFO - Running setup.py install for mozlog 21:22:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:22:20 INFO - Running setup.py install for moznetwork 21:22:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:22:20 INFO - Running setup.py install for mozprocess 21:22:21 INFO - Running setup.py install for mozprofile 21:22:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:22:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:22:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:22:21 INFO - Running setup.py install for mozrunner 21:22:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:22:21 INFO - Running setup.py install for mozscreenshot 21:22:21 INFO - Running setup.py install for moztest 21:22:21 INFO - Running setup.py install for mozversion 21:22:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:22:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:22:21 INFO - Cleaning up... 21:22:21 INFO - Return code: 0 21:22:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:22:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 21:22:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:22:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:22:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 21:22:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:22:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:22:21 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0xbe61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcdea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe83b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe81290>, '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 0xe81b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe82040>, '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': 'db0483c52d987ecb441a263b000001ad-1493868486.264432-1475407895', '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 21:22:21 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 21:22:21 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:22:21 INFO - Using env: {'DISPLAY': ':0', 21:22:21 INFO - 'HOME': '/home/cltbld', 21:22:21 INFO - 'LANG': 'en_US.UTF-8', 21:22:21 INFO - 'LANGUAGE': 'en_US:en', 21:22:21 INFO - 'LOGNAME': 'cltbld', 21:22:21 INFO - 'MAIL': '/var/mail/cltbld', 21:22:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:22:21 INFO - 'MOZ_NO_REMOTE': '1', 21:22:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:22:21 INFO - 'NO_EM_RESTART': '1', 21:22:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:22:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:22:21 INFO - 'PWD': '/builds/slave/test', 21:22:21 INFO - 'SHELL': '/bin/bash', 21:22:21 INFO - 'SHLVL': '1', 21:22:21 INFO - 'TERM': 'linux', 21:22:21 INFO - 'TMOUT': '86400', 21:22:21 INFO - 'USER': 'cltbld', 21:22:21 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1493868486.264432-1475407895', 21:22:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:22:21 INFO - '_': '/tools/buildbot/bin/python'} 21:22:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:22:21 INFO - Running setup.py (path:/tmp/pip-V8ZANu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:22:21 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)) 21:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:22:21 INFO - Running setup.py (path:/tmp/pip-vL6lqT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:22:21 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)) 21:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:22:22 INFO - Running setup.py (path:/tmp/pip-9xGjEX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:22:22 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)) 21:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:22:22 INFO - Running setup.py (path:/tmp/pip-twpPR8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:22:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:22:22 INFO - Running setup.py (path:/tmp/pip-akJPjg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:22:22 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)) 21:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:22:22 INFO - Running setup.py (path:/tmp/pip-89sheh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:22:22 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)) 21:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:22:22 INFO - Running setup.py (path:/tmp/pip-pmmbRx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:22:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 21:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:22:22 INFO - Running setup.py (path:/tmp/pip-m8yrXn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:22:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 21:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:22:22 INFO - Running setup.py (path:/tmp/pip-baQgkV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:22:22 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)) 21:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:22:22 INFO - Running setup.py (path:/tmp/pip-TgePIv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:22:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:22:22 INFO - Running setup.py (path:/tmp/pip-kKzBn6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:22:22 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)) 21:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:22:22 INFO - Running setup.py (path:/tmp/pip-GuxGsw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:22:22 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)) 21:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:22:22 INFO - Running setup.py (path:/tmp/pip-UDocJI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:22:22 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)) 21:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:22:22 INFO - Running setup.py (path:/tmp/pip-XNiBNL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:22:22 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)) 21:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:22:22 INFO - Running setup.py (path:/tmp/pip-zaoVyN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:22:22 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)) 21:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:22:22 INFO - Running setup.py (path:/tmp/pip-cVhrL6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:22:22 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)) 21:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:22:22 INFO - Running setup.py (path:/tmp/pip-cprOgY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:22:23 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)) 21:22:23 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)) 21:22:23 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)) 21:22:23 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)) 21:22:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:22:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:22:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:22:23 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 21:22:23 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 21:22:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:22:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:22:23 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 21:22:23 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 21:22:24 INFO - Downloading blessings-1.6.tar.gz 21:22:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:22:24 INFO - Installing collected packages: blessings 21:22:24 INFO - Running setup.py install for blessings 21:22:24 INFO - Successfully installed blessings 21:22:24 INFO - Cleaning up... 21:22:24 INFO - Return code: 0 21:22:24 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:22:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:22:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:22:24 INFO - Reading from file tmpfile_stdout 21:22:24 INFO - Current package versions: 21:22:24 INFO - argparse == 1.2.1 21:22:24 INFO - blessings == 1.6 21:22:24 INFO - blobuploader == 1.2.4 21:22:24 INFO - docopt == 0.6.1 21:22:24 INFO - functools32 == 3.2.3-2 21:22:24 INFO - jsonschema == 2.5.1 21:22:24 INFO - manifestparser == 1.1 21:22:24 INFO - mozInstall == 1.12 21:22:24 INFO - mozcrash == 1.0 21:22:24 INFO - mozdebug == 0.1 21:22:24 INFO - mozdevice == 0.50 21:22:24 INFO - mozfile == 1.2 21:22:24 INFO - mozhttpd == 0.7 21:22:24 INFO - mozinfo == 0.9 21:22:24 INFO - mozleak == 0.1 21:22:24 INFO - mozlog == 3.4 21:22:24 INFO - moznetwork == 0.27 21:22:24 INFO - mozprocess == 0.25 21:22:24 INFO - mozprofile == 0.28 21:22:24 INFO - mozrunner == 6.13 21:22:24 INFO - mozscreenshot == 0.1 21:22:24 INFO - mozsystemmonitor == 0.3 21:22:24 INFO - moztest == 0.8 21:22:24 INFO - mozversion == 1.4 21:22:24 INFO - psutil == 3.1.1 21:22:24 INFO - requests == 1.2.3 21:22:24 INFO - wsgiref == 0.1.2 21:22:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:22:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 21:22:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:22:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:22:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 21:22:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:22:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:22:24 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0xbe61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcdea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe83b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe81290>, '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 0xe81b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe82040>, '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': 'db0483c52d987ecb441a263b000001ad-1493868486.264432-1475407895', '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 21:22:24 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 21:22:24 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:22:24 INFO - Using env: {'DISPLAY': ':0', 21:22:24 INFO - 'HOME': '/home/cltbld', 21:22:24 INFO - 'LANG': 'en_US.UTF-8', 21:22:24 INFO - 'LANGUAGE': 'en_US:en', 21:22:24 INFO - 'LOGNAME': 'cltbld', 21:22:24 INFO - 'MAIL': '/var/mail/cltbld', 21:22:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:22:24 INFO - 'MOZ_NO_REMOTE': '1', 21:22:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:22:24 INFO - 'NO_EM_RESTART': '1', 21:22:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:22:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:22:24 INFO - 'PWD': '/builds/slave/test', 21:22:24 INFO - 'SHELL': '/bin/bash', 21:22:24 INFO - 'SHLVL': '1', 21:22:24 INFO - 'TERM': 'linux', 21:22:24 INFO - 'TMOUT': '86400', 21:22:24 INFO - 'USER': 'cltbld', 21:22:24 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1493868486.264432-1475407895', 21:22:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:22:24 INFO - '_': '/tools/buildbot/bin/python'} 21:22:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:22:24 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)) 21:22:24 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)) 21:22:24 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)) 21:22:24 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)) 21:22:24 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)) 21:22:24 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)) 21:22:24 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)) 21:22:24 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)) 21:22:24 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)) 21:22:24 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)) 21:22:24 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)) 21:22:24 INFO - Cleaning up... 21:22:24 INFO - Return code: 0 21:22:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:22:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 21:22:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:22:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:22:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 21:22:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:22:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:22:24 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0xbe61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcdea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe83b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe81290>, '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 0xe81b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe82040>, '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': 'db0483c52d987ecb441a263b000001ad-1493868486.264432-1475407895', '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 21:22:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 21:22:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 21:22:24 INFO - Using env: {'DISPLAY': ':0', 21:22:24 INFO - 'HOME': '/home/cltbld', 21:22:24 INFO - 'LANG': 'en_US.UTF-8', 21:22:24 INFO - 'LANGUAGE': 'en_US:en', 21:22:24 INFO - 'LOGNAME': 'cltbld', 21:22:24 INFO - 'MAIL': '/var/mail/cltbld', 21:22:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:22:24 INFO - 'MOZ_NO_REMOTE': '1', 21:22:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:22:24 INFO - 'NO_EM_RESTART': '1', 21:22:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:22:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:22:24 INFO - 'PWD': '/builds/slave/test', 21:22:24 INFO - 'SHELL': '/bin/bash', 21:22:24 INFO - 'SHLVL': '1', 21:22:24 INFO - 'TERM': 'linux', 21:22:24 INFO - 'TMOUT': '86400', 21:22:24 INFO - 'USER': 'cltbld', 21:22:24 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1493868486.264432-1475407895', 21:22:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:22:24 INFO - '_': '/tools/buildbot/bin/python'} 21:22:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:22:25 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:22:25 INFO - Cleaning up... 21:22:25 INFO - Return code: 0 21:22:25 INFO - Running post-action listener: _resource_record_post_action 21:22:25 INFO - Running post-action listener: _start_resource_monitoring 21:22:25 INFO - Starting resource monitoring. 21:22:25 INFO - [mozharness: 2017-05-04 04:22:25.048513Z] Finished create-virtualenv step (success) 21:22:25 INFO - [mozharness: 2017-05-04 04:22:25.049034Z] Running install step. 21:22:25 INFO - Running pre-action listener: _resource_record_pre_action 21:22:25 INFO - Running main action method: install 21:22:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:22:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:22:25 INFO - Reading from file tmpfile_stdout 21:22:25 INFO - Detecting whether we're running mozinstall >=1.0... 21:22:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:22:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:22:25 INFO - Reading from file tmpfile_stdout 21:22:25 INFO - Output received: 21:22:25 INFO - Usage: mozinstall [options] installer 21:22:25 INFO - Options: 21:22:25 INFO - -h, --help show this help message and exit 21:22:25 INFO - -d DEST, --destination=DEST 21:22:25 INFO - Directory to install application into. [default: 21:22:25 INFO - "/builds/slave/test"] 21:22:25 INFO - --app=APP Application being installed. [default: firefox] 21:22:25 INFO - mkdir: /builds/slave/test/build/application 21:22:25 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'] 21:22:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 21:22:41 INFO - Reading from file tmpfile_stdout 21:22:41 INFO - Output received: 21:22:41 INFO - /builds/slave/test/build/application/firefox/firefox 21:22:41 INFO - Running post-action listener: _resource_record_post_action 21:22:41 INFO - [mozharness: 2017-05-04 04:22:41.501648Z] Finished install step (success) 21:22:41 INFO - [mozharness: 2017-05-04 04:22:41.501920Z] Running run-tests step. 21:22:41 INFO - Running pre-action listener: _resource_record_pre_action 21:22:41 INFO - Running main action method: run_tests 21:22:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:22:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:22:41 INFO - Python 2.7.3 21:22:41 INFO - Return code: 0 21:22:41 INFO - grabbing minidump binary from tooltool 21:22:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 21:22:41 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe81290>, '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 0xe81b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe82040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:22:41 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 21:22:41 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 21:22:41 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 21:22:41 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:22:41 INFO - Return code: 0 21:22:41 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:22:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:22:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:22:41 INFO - ENV: RUST_BACKTRACE is now 1 21:22:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:22:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:22:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/c-MWHmbCRDKsZJWYrhhqaQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-031', '--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 21:22:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/c-MWHmbCRDKsZJWYrhhqaQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-031 --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 21:22:41 INFO - Using env: {'DISPLAY': ':0', 21:22:41 INFO - 'HOME': '/home/cltbld', 21:22:41 INFO - 'LANG': 'en_US.UTF-8', 21:22:41 INFO - 'LANGUAGE': 'en_US:en', 21:22:41 INFO - 'LOGNAME': 'cltbld', 21:22:41 INFO - 'MAIL': '/var/mail/cltbld', 21:22:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:22:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:22:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:22:41 INFO - 'MOZ_NO_REMOTE': '1', 21:22:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:22:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:22:41 INFO - 'NO_EM_RESTART': '1', 21:22:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:22:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:22:41 INFO - 'PWD': '/builds/slave/test', 21:22:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:22:41 INFO - 'RUST_BACKTRACE': '1', 21:22:41 INFO - 'SHELL': '/bin/bash', 21:22:41 INFO - 'SHLVL': '1', 21:22:41 INFO - 'TERM': 'linux', 21:22:41 INFO - 'TMOUT': '86400', 21:22:41 INFO - 'USER': 'cltbld', 21:22:41 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1493868486.264432-1475407895', 21:22:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:22:41 INFO - '_': '/tools/buildbot/bin/python'} 21:22:41 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/c-MWHmbCRDKsZJWYrhhqaQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-031', '--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 21:22:41 INFO - mozversion application_buildid: 20170504031950 21:22:41 INFO - mozversion application_changeset: d19472763d1dbc039a14260444781308b4e97f29 21:22:41 INFO - mozversion application_display_name: Nightly 21:22:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:22:41 INFO - mozversion application_name: Firefox 21:22:41 INFO - mozversion application_remotingname: firefox 21:22:41 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 21:22:41 INFO - mozversion application_vendor: Mozilla 21:22:41 INFO - mozversion application_version: 55.0a1 21:22:41 INFO - mozversion platform_buildid: 20170504031950 21:22:41 INFO - mozversion platform_changeset: d19472763d1dbc039a14260444781308b4e97f29 21:22:41 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 21:22:41 INFO - mozversion platform_version: 55.0a1 21:22:41 INFO - using testdate: 1493871761 21:22:41 INFO - actual date: 1493871761 21:22:41 INFO - starting webserver on 'localhost:35786' 21:22:41 INFO - SUITE-START | Running 2 tests 21:22:41 INFO - TEST-START | dromaeo_css 21:22:41 INFO - operating with platform_type : linux_ 21:22:41 INFO - Initialising browser for dromaeo_css test... 21:22:41 INFO - TEST-INFO | started process 9401 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKurb3c/profile http://localhost:35786/getInfo.html) 21:22:45 INFO - PID 9401 | __metrics Screen width/height:1600/1200 21:22:45 INFO - PID 9401 | colorDepth:24 21:22:45 INFO - PID 9401 | Browser inner width/height: 1024/695 21:22:45 INFO - PID 9401 | __metrics 21:22:47 INFO - TEST-INFO | 9401: exit 0 21:22:47 INFO - Browser initialized. 21:22:47 INFO - Running cycle 1/1 for dromaeo_css test... 21:22:47 INFO - TEST-INFO | started process 9573 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKurb3c/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:22:59 INFO - PID 9573 | RSS: Main: 156913664 21:22:59 INFO - PID 9573 | 21:25:59 INFO - PID 9573 | Cycle 1(1): loaded http://localhost:35786/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35786/tests/dromaeo/cssquery-ext.html) 21:26:03 INFO - PID 9573 | RSS: Main: 163405824 21:26:03 INFO - PID 9573 | 21:29:05 INFO - PID 9573 | Cycle 1(1): loaded http://localhost:35786/tests/dromaeo/cssquery-ext.html (next: http://localhost:35786/tests/dromaeo/cssquery-jquery.html) 21:29:05 INFO - PID 9573 | RSS: Main: 156946432 21:29:05 INFO - PID 9573 | 21:32:07 INFO - PID 9573 | Cycle 1(1): loaded http://localhost:35786/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35786/tests/dromaeo/cssquery-mootools.html) 21:32:07 INFO - PID 9573 | RSS: Main: 165601280 21:32:07 INFO - PID 9573 | 21:35:09 INFO - PID 9573 | Cycle 1(1): loaded http://localhost:35786/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35786/tests/dromaeo/cssquery-prototype.html) 21:35:10 INFO - PID 9573 | RSS: Main: 157810688 21:35:10 INFO - PID 9573 | 21:38:12 INFO - PID 9573 | Cycle 1(1): loaded http://localhost:35786/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35786/tests/dromaeo/cssquery-yui.html) 21:38:13 INFO - PID 9573 | RSS: Main: 159944704 21:38:13 INFO - PID 9573 | 21:41:15 INFO - PID 9573 | Cycle 1(1): loaded http://localhost:35786/tests/dromaeo/cssquery-yui.html (next: http://localhost:35786/tests/dromaeo/cssquery-dojo.html) 21:41:15 INFO - PID 9573 | RSS: Main: 162512896 21:41:15 INFO - PID 9573 | 21:41:15 INFO - PID 9573 | __start_tp_report 21:41:15 INFO - PID 9573 | _x_x_mozilla_page_load 21:41:15 INFO - PID 9573 | _x_x_mozilla_page_load_details 21:41:15 INFO - PID 9573 | |i|pagename|runs| 21:41:15 INFO - PID 9573 | |0;dojo.html;3327.9500807487884;3337.2324365395957;3348.4140275451796;3370.342783157282;3370.646082161373;5601.327840659116;5694.658320500773;5709.115087161486;5759.712014399279;5764.740586673599;5690.2033715279895;5697.886042279152;5715.77136914524;5788.334341550723;5834.5915785895;5042.974785126087;5100.948990510103;5107.897842043157;5114;5114.232865070232;5143.125668636322;5179.249008893708;5197.922031169535;5204.661696989683;5210.114280572312;5062.54437100659;5103.2855400244;5133.050385678662;5138.177891537336;5164.793408263665;5086.872828179288;5088.313077734532;5093.057784309877;5108.386993560759;5159.045576568347;5046.495350464924;5102.872428189325;5103.387593488805;5109.8722531937;5118.667286626357;5603.971980140114;5621.971890140564;5672.376038635747;5735.598508104435;5761.53907687384;6184.07238914162;6196.597221180654;6215.782447614311;6219.253689557237;6232.626042437469;6159.3224745277985;6244.87510249797;6245.375462453764;6246.187995560594;6261.029540421242;5673.744681489343;5740.712964351849;5750.856228594319;5824.242848429677;5835.153902684087;6340.080688300171;6357.237131544244;6362.331955144643;6375;6376.29860715321;5930.673812940329;5943.405659434022;6047.909281360783;6095.66473843934;6110.694465276719;6218.347073557302;6220.129181914535;6222.066689996446;6225.750969961151;6238.781642642486;6077.878442431127;6124.846878827976;6143.56995010354;6147.170132032208;6205.069239614004;6157.568970172135;6213.03697926822;6229.657368844666;6239.939210334254;6242.1261023456755;4727.763611819395;4738.0523895221095;4772.522747725269;4801.639877009168;4809.44691360491;4186.790660466964;4214.3678448232395;4244.193603215379;4260.105377870682;4268.487781466244;5912.408759124053;5915.970420147958;5946.672932988728;5964.224650795456;5980.342162362137;6165.876682466415;6212.192414986023;6212.937870621236;6214.285357183891;6214.471769899519;5742.454466825645;5875.706214689249;5916.615419997686;5919.260092488439;5936.7328470218945;5329.680219186784;5345.091334473149;5362.597805164628;5366.91949620756;5394.298741163623;6446.613203207822;6446.7421303148285;6454.225492940784;6580.276169621242;6581.638009909309;6179;6188.783392581418;6197.287311959002;6198.380161983946;6217.533684973555;6466.353364663496;6517.250516190698;6532.281449040506;6535.313792051957;6543.312952140147;6021.7591296347655;6040.516758659384;6097.7865774697675;6118.60229085108;6124.6325220486915;6050.485708714808;6114.724837382418;6156.199694039746;6187.84530386744;6210.813675589739;5935.228420305332;5983.670898100472;5992.161097446448;5994.880102398015;6010.4891084258325;5979.790707325223;5982.341942386421;5984.1921340618455;6003.549733769897;6007.909881351696;5918.171455996078;5972.850678733066;5973.044312909914;5982.551308651781;5989.580729348903;5985.371535988833;5989.251343582052;6033.155358249936;6036.8188954331445;6047.244094488189;5843.474087332161;5865.472107510344;5866.413358664099;5866.914620795166;5900.616459930091 21:41:15 INFO - PID 9573 | |1;ext.html;814.2264848394025;834.182501148874;1126.6563698072084;1139.5384869128006;1220.4568966809761;35967.820160899195;38151.236975260515;38167.236655266875;38329.61670383295;38350.808245958804;35783.284334313365;36199.638003619955;36215.275694486096;36292.45561316576;36331.273374532495;6825.897611535831;6956.860862782742;6961.23426423093;6969.128858892638;7247.057882475271;7354.264573542657;7484.101907771075;7499.437542184331;7503.474756767028;7515.887261691052;7416.0729908761405;7510.399168066541;7524;7527.435442341846;7540.095188577378;7428.368588669916;7469.813254668622;7488.363489103433;7492.100947886262;7495.775126746206;7275.1633562140505;7280.890786638205;7287.817804554876;7313.853722925565;7337.41300695943;36685.633143668485;37077.814610927046;37214;37217.25565488702;37304.62695373066;3488.9825550872083;3516.542849429558;3518.665726755954;3522.630123836986;3525.8413371398346;29015.709842901513;29180.562291565642;29347.41305173906;29409.11772646824;29411.705882941325;3754.305453491113;3770.453284273792;3773.660370566662;3794.5636251831115;3798.0504873781556;194944;195489;195505;196456.99999999857;197649;45125.548744512795;47517.52482475131;47814.043719126115;48353;48424.273635895486;49537.25694114591;49854.50145498499;49889.75055124774;49892.750536247084;50066.49933500691;14722.337494812758;14752.852471475362;14853.48012819546;14921.179335136454;14927;11599;11659.300441973508;11717.414129293502;11725.179237453296;11776.58781942645;14339.856601433852;14362.928185359006;14418.495352662607;14476.27618619065;14570.344334504973;5594.888102237933;5712;5720.11338242573;5740.913886291709;5764.117941029485;1091.1979694924225;1117.8267368557886;1125.6904351303294;1128.4808987865674;1139.1968662093204;25513.851876665594;26330;26542.336441589116;26556.203313900613;26656.60015099775;1183.2722875431548;1194.7431302270054;1209;1217.3183017510223;1227.2329793878826;13650.522231721841;13850.030497865053;16974.83025169757;17506.912465437847;17808.732869006973;271665;272045;273172;275237;276116;57598.7120064397;57858.710706446196;57890.710546447;57893.42106578965;58058;1448.8551144885637;1475.6532214929482;1492.2165862922052;1510.8489151085023;1518.9012714173764;28215;29661;30117.247068823453;30443.695563044086;30900.22749431282;38839.80580097081;39029.21941561096;39083.2183356337;39099;39271.214575708895;225367;226406;226810;227694;227967;33283.33433331368;34384.656153439144;35269.64730352664;35813.641863582074;36007.81996090003;42166.15667686691;43028.56971430371;45303.54696452993;45811.77094114642;46000.309995349424;32116.19709507281;32435.18912027218;32718;33594.88723603723;33884.152896177795;32649.34701306008;32976.505352419255;33051.83474082711;33933.830330848185;34239.315213696085;38444.42333364946;38474.230515388976;38717;38900.610993890834;38961.805190975;30625.846870765505;30647.693523064485;30661;31488;33142.337153257286;24949.625755614044;26054.60918086268;26204.73795262023;26225.868870655526;26453 21:41:15 INFO - PID 9573 | |2;jquery.html;438.99561004389966;448.6769525941322;448.77561219390304;457.09723296489426;465.88352911772057;20086.89956550217;21049.789502104974;21425;21478.57042859144;21661.35015949524;22087.66868496974;22349.553008939813;22725.9773310201;22757.544849103007;22891.427714307152;4392.77131308488;5499.312585926759;5677.148427735852;5681.3466451358;5688.943110568904;5511.724413779315;5624.718764061801;5631.60578759487;5634.605577609569;5638.548916086703;5381.515663590276;5508.338999320087;5584.497395234428;5589.888202235953;5612.635178713371;5556.444355564452;5565.41563135871;5582.078956972095;5582.804601838916;5599.076152434844;5018.64869459138;5023.648344615878;5044.47033061527;5047.899042019139;5059.203982586166;25666.358341041436;25786.48427031449;25887;26058;26291.07981220648;2151.505153814616;2170.804627583509;2191.9780802192095;2194.868307901531;2195.1548653768255;16912.66174676512;17191.91404042984;17344.392946246822;17490.825091749048;17687;2174.5868285025795;2204.1954686539298;2270.080617349976;2287.3824067501682;2289.301762962296;107871.46064269629;108600;111524.88475115226;111686.44156779246;113413.43293283564;12047.337396443105;12061.216020958609;12510.49958001688;12528.81206781899;12624.431900564497;16894;16951.49145525636;16964.406245781338;17039.744403833953;17069.06120163378;8312.33501319905;8590.914090859136;8678.392512524062;8845.380823342304;8880.111988801069;7827.608619569112;7846.450748447553;8004.91933588958;8131.715389961323;8162.510249385056;7798.958406221578;7827.608619569112;7999.720009799629;8086.555239461768;8095.7166499172245;2254.9098036078703;2269.795718385353;2276.1236923784463;2289.439087423592;2463.2856471623027;998.8202123617673;1013.4932533733133;1056.793925184597;1066.0831684751108;1079.8272276435732;19839;20364.185432582828;20852.687209691867;21005.68491472629;21289.78710212909;1077.0306723948509;1081.1837063017529;1101.658485869383;1110.4947249001684;1120.5293776613842;10882.932242873829;11352.148588855704;11353.37556434404;11361.997722628048;11386.829197562043;488480;503469;504519;516421;532157;91582.54208728914;92622.53688731648;95575;96335.51832240794;100116.49941750245;1258.4840215511597;1259.5339724302066;1308.8822006019504;1313.6847156682327;1324.0996122636507;20909.26817561378;21120.894395527925;21672.133114675238;21875.343739687836;21901.67147492757;45083;45499;46607;46726.766366167954;47212.52787472219;58701;59671.13781915135;59799.402005979384;60338.69830650819;60593.0911036326;9410.341276110606;9580.856287155837;9677.516124193622;9778.168855647065;9863.933715640966;14031.859681403055;14271.215083170526;14336.713265734836;14603.634909126931;15543.222838858239;12553.560625378068;12987.090903636654;13157.60527184186;13169.802452963404;13358.064935454426;11657.88342116602;11779.234349767237;12149.574764883188;12151.453184606891;12190.51237950472;12003.578979990662;12502.624921252376;12675.93662031684;12698;13530.255835928725;9357.906420935704;9541.427514349161;9640.855387169338;9719.319647624881;9726.513674316397;12997.740045199233;13425.127366721132;13945.511907083204;14173.574792756233;14338.56984290473 21:41:15 INFO - PID 9573 | |3;mootools.html;1309.5791066692639;1358.6399604104909;1363.4546181527382;1373.1417863835104;1376.4150236149637;10520.78958420831;10885.510152043158;10949.397783121925;11059.115270778339;11256.943715281433;11053.392063436508;11158.609448669298;11183.888161118386;11185.32888026719;11198.328100313987;2180.35679474555;2209.039068005423;2218.0684112672634;2219.9334019979347;2222.5777102350585;2202.1081462007915;2267.399139228106;2273.977260227393;2274.6133157363206;2275.7838005389544;2256.0975609756147;2277.2826559633663;2306.3657494189065;2317.1079134533243;2320.5938960681815;2164.231697747293;2174.402039439159;2216.3683350245183;2222.5443784024237;2222.9110835566476;2151.085788539864;2176.9020394082304;2179.574982878339;2288.279192054514;2313.3869524576003;10881.075108615778;10924.399158046304;10984.725381865437;10987.066099381562;11009.724756881062;6439.517036222255;6670.933290667128;6673.833154171136;6689.23073846509;6690.293975036383;3798.924021519554;3871.2257548490416;3905.1799122184093;4057.675385969145;4149.543550209474;1273.3887733887693;1349.8920086393066;1409.2953523238382;1458.4166333466592;1475.6901050779243;15275.083494990336;15291.847081529264;15322.15728134952;15386.692266154727;15391.538253852403;5158.4067832199025;5169.116388270654;5339.519443250048;5418.485243901823;5543.722813859291;11299.378534180534;11343.43282835855;11391.601293954673;11394.373309467894;11452.54189832414;6283.277423096311;6371.52213583983;6387.680615969183;6396.040593910858;6412.326705695835;5884.852878678067;6059.394060593993;6069.878602427927;6135.601185922901;6142.140100385949;4129.359949207877;4131.5455299916985;4206.305959516645;4217.557156498524;4286.549912259168;1231.5221733919332;1242.27327013698;1255.535451882791;1257.5535684831937;1265.2218885385605;874.6326542852017;897.8608315711075;905.158202871336;907.5507623726297;916.0564618443015;6526.510511711641;6549.803505894831;6558.5409021369;6679.532432729662;6680.198376194888;645.7287939065603;646.6055706019324;647.3170804800886;652.6801867085189;659.5416185750857;4033.1328764315813;4143.399207114951;4366.956330436718;4396.230659634552;4424.6902716810155;13816.516421925184;14269.643258918402;14292.071015384176;14416.207108608918;14437.927810360881;13316.800247996287;14208.431662733581;14225;14230.501932432315;14250.002499824912;8887.422317549339;9018.143276388733;9039.593218305192;9047.366684332164;9078.273738101068;6728.226253980855;6914.55055421396;7007.964960175166;7092.468064895257;7128.28717128283;12330.445129968994;12470.189437686522;12609.684757881127;12781.04142189358;12818;12534.185277956904;12567.497300107892;12972.805407919077;13070.673233168867;13088;9175.22010629104;9339.889379107039;9378.343515954091;9402.200812930978;9411.95294023553;10563.735906602396;10691.09125724322;10735.516901739284;10742.3017503862;10753.569857205624;8975.640974360953;9165.404248723811;9208.585613647536;9367.906320936703;9422.387544809566;9145.131212534798;9184.08159184103;9239.39943903644;9288.210502107397;9516.85724714116;8272.627731752205;8304;8358.080611132893;8451;8460.450070745383;6229.875402492015;6235.96882015587;6260.874782504416;6492.285848556749;6521.184851893513;6452.387023232785;6452.419282264619;6455.2576453708425;6627.768028118992;6686.5653732507235 21:41:15 INFO - PID 9573 | |4;prototype.html;418.6713429957483;421.7933212725764;425.58718043497834;429.0732018839308;432.6863024307378;5942.405759424056;6068.150458935753;6077.635341879491;6084.72618732157;6099.57302988791;5844.707764611763;5918.585699001072;5922.200502932103;5931.839299809172;5970.253718285215;4350.738955662655;4437.778111094449;4461.598456138947;4469.687121901468;4472.2173619616515;4432.534583868695;4441.600255976961;4454.844080457185;4458.9331160032625;4467.352233926079;4414.933775993362;4436.489803672571;4441.222786012451;4444.377787109807;4445.51099379068;4371.125774845043;4413.492448368446;4442.067165895166;4444.022315090707;4456.420665313521;3836.251930873481;3848.4034974578985;3853.421986702017;3856.2480316338324;3856.5179352580926;5788.913166302509;5843.620164689281;5845.165094928956;5846.473817356458;5856.941430585724;5121.180611102243;5201.271821944931;5226.242194881721;5247.160454327328;5252;6288.685565721696;6304;6309.589876658002;6311.431971122575;6321.336259692758;4272.679549033804;4349.652027837766;4350.695451318409;4352.36890650857;4366.519682834917;6344.809655710336;6584.407403333723;6614.735410583571;6614.86770264592;6664.500162487833;4391.275439552502;4414.911701766011;4415.4259946206785;4426.667999900021;4433.8226470941445;4468.218061839165;4477.925297928474;4483.19302525542;4494.191045611821;4512.165249428866;5131.897362052738;5191.6625419348375;5207.114790485626;5230.633855630145;5250.97374513125;6251.249850018027;6305.621662700252;6316.178896743486;6337.96831015842;6378.617282963037;1523.2764436892387;1537.5239769820873;1544.1970175508673;1546.5360391882616;1546.8839837012044;1010.2927950434726;1033.3696445168441;1034.255336157965;1037.0407373179778;1045.2056437107854;6140.539459540463;6152;6159.583295841981;6164.321924588292;6213.627182369072;6358.618482891041;6470.835249655013;6480.157579514727;6518.902216466757;6527.640979746065;6090.878182436327;6116.898958187569;6118.969405152946;6132.41742034506;6137.294211165773;3371.4268574342423;3453.827308634558;3456.1705190754037;3456.716119686308;3471.392506311385;6647.5014373921185;6816;6825.590464572042;6874.243833178346;6877;6598.769043083469;6620.139381880324;6656.234533028667;6673.366030227121;6712.160979877515;6603.306652801482;6779.423748981392;6866.793996180023;6875.5530890491955;6877.724891004404;6443.226812782309;6479.783774061925;6514.674266286762;6545.050967609574;6545.476361891133;6384.904226436514;6397.744090236524;6464.2889282177985;6491.772787952399;6506;6738.663066846736;6907.585544867324;6916.723331066701;6937.445004399535;6963.79108626742;4440.20362589981;4450.376947227455;4455.108978204307;4467.195904737178;4472.865814025455;6511;6533.705983230671;6568.113304703995;6581.276059633341;6619.3380661933425;6324.905126423016;6349.77775777864;6368.808935731936;6372.394622510854;6386.584871983307;6267.749290028531;6291.748330066746;6326.746930122928;6393.424591786761;6408.487321014214;6313.747450102128;6350.333215012357;6371.49028077762;6379.553431259953;6393.2647745508;6255.841237646411;6385.425311721968;6397.96801015992;6427.550071495138;6464.224293084671;6188.319284878749;6204.224471941007;6214.161088253028;6225.31521532622;6225.719842607003 21:41:15 INFO - PID 9573 | |5;yui.html;850.1948772322519;875.1773333066917;875.5447167472918;883.2095274729118;899.1323372945106;13836.654083647914;14341.56975290743;15565.766513502307;15997.120158391284;16062;15940.362385504566;16645.417410390608;16815.243314050866;17035.403760868372;17161.14194290287;1016.9694909152719;1043.6608102366724;1045.9424731639756;1047.2669131607868;1047.287844265899;1022.5091955861191;1023.3603997501561;1026.112412762962;1026.9024442677971;1028.4960369418968;1075.4891426572356;1075.6826736112826;1075.8655168103987;1082.783443311337;1097.1497089755424;1077.6874706335154;1085.696005118568;1087.1954753482446;1088.086007753487;1090.7545802194522;793.8491686579531;793.8690116130831;795.3716563914559;796.0765512006103;796.52606699014;15449;15760.211989400485;15886.602834929103;16014.839851601568;16023.11872846993;18055.638887222314;18187.27250909962;18299.634007319914;18305.908470457696;18438.354657587053;14008.439662413492;14035.1578905265;14089;14093.365798539091;14121;3180.6183258008955;3183.4588120019653;3211.8554665039896;3226.161198088514;3226.209578653245;93805;95341;96078;96787;97280.51359743226;12535.749285014248;12726;12889.806652900214;12984.610461686165;13017.153884997444;21704.131834726435;21873.781262187174;22032.11871525153;22076.88961555182;22083.889580551993;8401.411901166859;8645.95677021611;8729.956350218336;8738.995015573164;8744.519051452231;3879.1078052048188;3926.1951299983434;3927.155661532784;3928.0769019280447;3951.68386529077;4511.70673906195;4658.767061646904;4675.649326300541;4693.859184224479;4717.740524271129;921.4056933277998;944.4097439100562;945.7162851144767;955.694177863091;959.9568019439142;711.434409644334;713.3437237741291;720.9134903811577;721.6463932672953;732.5092188233896;11425.485853136628;12907.354632268349;13202;13503.86496135026;13537.458501659823;716.1799739298474;730.4886579394445;736.2600586410623;746.138210367027;753.1865585168115;5458.426865179178;5883;6286.308506064329;6473.805785826433;6611.570247933869;81420.59289703514;83720.58139709262;84496.57751711202;85614;88573;74454;74686.25313746902;76100;77575.22424775793;78162.6091869537;67551.66224168848;67647.32352676509;68145.65927170332;68524.6573767128;70018.94971575431;12668.809967850306;13268.469261229442;13380.665483362995;13383.19700817954;13389.062765606313;34364.31271374608;34537;34679;35038.64961350354;35763.82118089393;43116.353254700574;43286.56713432951;43466;43753.343699845165;45515;14736.115833050051;15549.300281487587;15833.68332633364;17412.39056633012;17803.554911127325;19041.42875713731;19587.902060489607;19669.901650491654;19689;20181.990900455214;17839.375621853174;17855.553611159306;18299.359522416653;18422.81577184211;18966.52583685419;16975.23611437513;18302.26790928348;18384.264629415207;18417.723734143732;18808.247670093042;16361.181940902668;16492.17539123015;16500.33998640089;16902.154892255585;18084.553235741372;12253.938730306649;12430.564930227398;12493.375331233221;12704.174228675107;12859.807102893652;14790.556283311518;14919.701605968037;15002.474913377979;15208.771868421762;15261.389544418544 21:41:15 INFO - PID 9573 | __end_tp_report 21:41:15 INFO - PID 9573 | __start_cc_report 21:41:15 INFO - PID 9573 | _x_x_mozilla_cycle_collect,335 21:41:15 INFO - PID 9573 | __end_cc_report 21:41:15 INFO - PID 9573 | __startTimestamp1493872875254__endTimestamp 21:41:15 INFO - PID 9573 | 21:41:15 INFO - PID 9573 | ------- Summary: start ------- 21:41:15 INFO - PID 9573 | Number of tests: 6 21:41:15 INFO - PID 9573 | 21:41:15 INFO - PID 9573 | [#0] dojo.html Cycles:165 Average:5724.98 Median:5964.22 stddev:671.67 (11.3%) stddev-sans-first:646.87 21:41:15 INFO - PID 9573 | Values: 3328.0 3337.2 3348.4 3370.3 3370.6 5601.3 5694.7 5709.1 5759.7 5764.7 5690.2 5697.9 5715.8 5788.3 5834.6 5043.0 5100.9 5107.9 5114.0 5114.2 5143.1 5179.2 5197.9 5204.7 5210.1 5062.5 5103.3 5133.1 5138.2 5164.8 5086.9 5088.3 5093.1 5108.4 5159.0 5046.5 5102.9 5103.4 5109.9 5118.7 5604.0 5622.0 5672.4 5735.6 5761.5 6184.1 6196.6 6215.8 6219.3 6232.6 6159.3 6244.9 6245.4 6246.2 6261.0 5673.7 5740.7 5750.9 5824.2 5835.2 6340.1 6357.2 6362.3 6375.0 6376.3 5930.7 5943.4 6047.9 6095.7 6110.7 6218.3 6220.1 6222.1 6225.8 6238.8 6077.9 6124.8 6143.6 6147.2 6205.1 6157.6 6213.0 6229.7 6239.9 6242.1 4727.8 4738.1 4772.5 4801.6 4809.4 4186.8 4214.4 4244.2 4260.1 4268.5 5912.4 5916.0 5946.7 5964.2 5980.3 6165.9 6212.2 6212.9 6214.3 6214.5 5742.5 5875.7 5916.6 5919.3 5936.7 5329.7 5345.1 5362.6 5366.9 5394.3 6446.6 6446.7 6454.2 6580.3 6581.6 6179.0 6188.8 6197.3 6198.4 6217.5 6466.4 6517.3 6532.3 6535.3 6543.3 6021.8 6040.5 6097.8 6118.6 6124.6 6050.5 6114.7 6156.2 6187.8 6210.8 5935.2 5983.7 5992.2 5994.9 6010.5 5979.8 5982.3 5984.2 6003.5 6007.9 5918.2 5972.9 5973.0 5982.6 5989.6 5985.4 5989.3 6033.2 6036.8 6047.2 5843.5 5865.5 5866.4 5866.9 5900.6 21:41:15 INFO - PID 9573 | 21:41:15 INFO - PID 9573 | [#1] ext.html Cycles:180 Average:40013.10 Median:3502.76 stddev:60954.28 (1740.2%) stddev-sans-first:61054.21 21:41:15 INFO - PID 9573 | Values: 814.2 834.2 1126.7 1139.5 1220.5 35967.8 38151.2 38167.2 38329.6 38350.8 35783.3 36199.6 36215.3 36292.5 36331.3 6825.9 6956.9 6961.2 6969.1 7247.1 7354.3 7484.1 7499.4 7503.5 7515.9 7416.1 7510.4 7524.0 7527.4 7540.1 7428.4 7469.8 7488.4 7492.1 7495.8 7275.2 7280.9 7287.8 7313.9 7337.4 36685.6 37077.8 37214.0 37217.3 37304.6 3489.0 3516.5 3518.7 3522.6 3525.8 29015.7 29180.6 29347.4 29409.1 29411.7 3754.3 3770.5 3773.7 3794.6 3798.1 194944.0 195489.0 195505.0 196457.0 197649.0 45125.5 47517.5 47814.0 48353.0 48424.3 49537.3 49854.5 49889.8 49892.8 50066.5 14722.3 14752.9 14853.5 14921.2 14927.0 11599.0 11659.3 11717.4 11725.2 11776.6 14339.9 14362.9 14418.5 14476.3 14570.3 5594.9 5712.0 5720.1 5740.9 5764.1 1091.2 1117.8 1125.7 1128.5 1139.2 25513.9 26330.0 26542.3 26556.2 26656.6 1183.3 1194.7 1209.0 1217.3 1227.2 13650.5 13850.0 16974.8 17506.9 17808.7 271665.0 272045.0 273172.0 275237.0 276116.0 57598.7 57858.7 57890.7 57893.4 58058.0 1448.9 1475.7 1492.2 1510.8 1518.9 28215.0 29661.0 30117.2 30443.7 30900.2 38839.8 39029.2 39083.2 39099.0 39271.2 225367.0 226406.0 226810.0 227694.0 227967.0 33283.3 34384.7 35269.6 35813.6 36007.8 42166.2 43028.6 45303.5 45811.8 46000.3 32116.2 32435.2 32718.0 33594.9 33884.2 32649.3 32976.5 33051.8 33933.8 34239.3 38444.4 38474.2 38717.0 38900.6 38961.8 30625.8 30647.7 30661.0 31488.0 33142.3 24949.6 26054.6 26204.7 26225.9 26453.0 21:41:15 INFO - PID 9573 | 21:41:15 INFO - PID 9573 | [#2] jquery.html Cycles:180 Average:31620.91 Median:2269.94 stddev:84424.63 (3719.2%) stddev-sans-first:84629.00 21:41:15 INFO - PID 9573 | Values: 439.0 448.7 448.8 457.1 465.9 20086.9 21049.8 21425.0 21478.6 21661.4 22087.7 22349.6 22726.0 22757.5 22891.4 4392.8 5499.3 5677.1 5681.3 5688.9 5511.7 5624.7 5631.6 5634.6 5638.5 5381.5 5508.3 5584.5 5589.9 5612.6 5556.4 5565.4 5582.1 5582.8 5599.1 5018.6 5023.6 5044.5 5047.9 5059.2 25666.4 25786.5 25887.0 26058.0 26291.1 2151.5 2170.8 2192.0 2194.9 2195.2 16912.7 17191.9 17344.4 17490.8 17687.0 2174.6 2204.2 2270.1 2287.4 2289.3 107871.5 108600.0 111524.9 111686.4 113413.4 12047.3 12061.2 12510.5 12528.8 12624.4 16894.0 16951.5 16964.4 17039.7 17069.1 8312.3 8590.9 8678.4 8845.4 8880.1 7827.6 7846.5 8004.9 8131.7 8162.5 7799.0 7827.6 7999.7 8086.6 8095.7 2254.9 2269.8 2276.1 2289.4 2463.3 998.8 1013.5 1056.8 1066.1 1079.8 19839.0 20364.2 20852.7 21005.7 21289.8 1077.0 1081.2 1101.7 1110.5 1120.5 10882.9 11352.1 11353.4 11362.0 11386.8 488480.0 503469.0 504519.0 516421.0 532157.0 91582.5 92622.5 95575.0 96335.5 100116.5 1258.5 1259.5 1308.9 1313.7 1324.1 20909.3 21120.9 21672.1 21875.3 21901.7 45083.0 45499.0 46607.0 46726.8 47212.5 58701.0 59671.1 59799.4 60338.7 60593.1 9410.3 9580.9 9677.5 9778.2 9863.9 14031.9 14271.2 14336.7 14603.6 15543.2 12553.6 12987.1 13157.6 13169.8 13358.1 11657.9 11779.2 12149.6 12151.5 12190.5 12003.6 12502.6 12675.9 12698.0 13530.3 9357.9 9541.4 9640.9 9719.3 9726.5 12997.7 13425.1 13945.5 14173.6 14338.6 21:41:15 INFO - PID 9573 | 21:41:15 INFO - PID 9573 | [#3] mootools.html Cycles:180 Average:6928.53 Median:3835.07 stddev:4284.65 (111.7%) stddev-sans-first:4275.86 21:41:15 INFO - PID 9573 | Values: 1309.6 1358.6 1363.5 1373.1 1376.4 10520.8 10885.5 10949.4 11059.1 11256.9 11053.4 11158.6 11183.9 11185.3 11198.3 2180.4 2209.0 2218.1 2219.9 2222.6 2202.1 2267.4 2274.0 2274.6 2275.8 2256.1 2277.3 2306.4 2317.1 2320.6 2164.2 2174.4 2216.4 2222.5 2222.9 2151.1 2176.9 2179.6 2288.3 2313.4 10881.1 10924.4 10984.7 10987.1 11009.7 6439.5 6670.9 6673.8 6689.2 6690.3 3798.9 3871.2 3905.2 4057.7 4149.5 1273.4 1349.9 1409.3 1458.4 1475.7 15275.1 15291.8 15322.2 15386.7 15391.5 5158.4 5169.1 5339.5 5418.5 5543.7 11299.4 11343.4 11391.6 11394.4 11452.5 6283.3 6371.5 6387.7 6396.0 6412.3 5884.9 6059.4 6069.9 6135.6 6142.1 4129.4 4131.5 4206.3 4217.6 4286.5 1231.5 1242.3 1255.5 1257.6 1265.2 874.6 897.9 905.2 907.6 916.1 6526.5 6549.8 6558.5 6679.5 6680.2 645.7 646.6 647.3 652.7 659.5 4033.1 4143.4 4367.0 4396.2 4424.7 13816.5 14269.6 14292.1 14416.2 14437.9 13316.8 14208.4 14225.0 14230.5 14250.0 8887.4 9018.1 9039.6 9047.4 9078.3 6728.2 6914.6 7008.0 7092.5 7128.3 12330.4 12470.2 12609.7 12781.0 12818.0 12534.2 12567.5 12972.8 13070.7 13088.0 9175.2 9339.9 9378.3 9402.2 9412.0 10563.7 10691.1 10735.5 10742.3 10753.6 8975.6 9165.4 9208.6 9367.9 9422.4 9145.1 9184.1 9239.4 9288.2 9516.9 8272.6 8304.0 8358.1 8451.0 8460.5 6229.9 6236.0 6260.9 6492.3 6521.2 6452.4 6452.4 6455.3 6627.8 6686.6 21:41:15 INFO - PID 9573 | 21:41:15 INFO - PID 9573 | [#4] prototype.html Cycles:180 Average:5284.30 Median:6108.24 stddev:1621.13 (26.5%) stddev-sans-first:1584.01 21:41:15 INFO - PID 9573 | Values: 418.7 421.8 425.6 429.1 432.7 5942.4 6068.2 6077.6 6084.7 6099.6 5844.7 5918.6 5922.2 5931.8 5970.3 4350.7 4437.8 4461.6 4469.7 4472.2 4432.5 4441.6 4454.8 4458.9 4467.4 4414.9 4436.5 4441.2 4444.4 4445.5 4371.1 4413.5 4442.1 4444.0 4456.4 3836.3 3848.4 3853.4 3856.2 3856.5 5788.9 5843.6 5845.2 5846.5 5856.9 5121.2 5201.3 5226.2 5247.2 5252.0 6288.7 6304.0 6309.6 6311.4 6321.3 4272.7 4349.7 4350.7 4352.4 4366.5 6344.8 6584.4 6614.7 6614.9 6664.5 4391.3 4414.9 4415.4 4426.7 4433.8 4468.2 4477.9 4483.2 4494.2 4512.2 5131.9 5191.7 5207.1 5230.6 5251.0 6251.2 6305.6 6316.2 6338.0 6378.6 1523.3 1537.5 1544.2 1546.5 1546.9 1010.3 1033.4 1034.3 1037.0 1045.2 6140.5 6152.0 6159.6 6164.3 6213.6 6358.6 6470.8 6480.2 6518.9 6527.6 6090.9 6116.9 6119.0 6132.4 6137.3 3371.4 3453.8 3456.2 3456.7 3471.4 6647.5 6816.0 6825.6 6874.2 6877.0 6598.8 6620.1 6656.2 6673.4 6712.2 6603.3 6779.4 6866.8 6875.6 6877.7 6443.2 6479.8 6514.7 6545.1 6545.5 6384.9 6397.7 6464.3 6491.8 6506.0 6738.7 6907.6 6916.7 6937.4 6963.8 4440.2 4450.4 4455.1 4467.2 4472.9 6511.0 6533.7 6568.1 6581.3 6619.3 6324.9 6349.8 6368.8 6372.4 6386.6 6267.7 6291.7 6326.7 6393.4 6408.5 6313.7 6350.3 6371.5 6379.6 6393.3 6255.8 6385.4 6398.0 6427.6 6464.2 6188.3 6204.2 6214.2 6225.3 6225.7 21:41:15 INFO - PID 9573 | 21:41:15 INFO - PID 9573 | [#5] yui.html Cycles:180 Average:19348.38 Median:19314.67 stddev:24196.97 (125.3%) stddev-sans-first:24224.98 21:41:15 INFO - PID 9573 | Values: 850.2 875.2 875.5 883.2 899.1 13836.7 14341.6 15565.8 15997.1 16062.0 15940.4 16645.4 16815.2 17035.4 17161.1 1017.0 1043.7 1045.9 1047.3 1047.3 1022.5 1023.4 1026.1 1026.9 1028.5 1075.5 1075.7 1075.9 1082.8 1097.1 1077.7 1085.7 1087.2 1088.1 1090.8 793.8 793.9 795.4 796.1 796.5 15449.0 15760.2 15886.6 16014.8 16023.1 18055.6 18187.3 18299.6 18305.9 18438.4 14008.4 14035.2 14089.0 14093.4 14121.0 3180.6 3183.5 3211.9 3226.2 3226.2 93805.0 95341.0 96078.0 96787.0 97280.5 12535.7 12726.0 12889.8 12984.6 13017.2 21704.1 21873.8 22032.1 22076.9 22083.9 8401.4 8646.0 8730.0 8739.0 8744.5 3879.1 3926.2 3927.2 3928.1 3951.7 4511.7 4658.8 4675.6 4693.9 4717.7 921.4 944.4 945.7 955.7 960.0 711.4 713.3 720.9 721.6 732.5 11425.5 12907.4 13202.0 13503.9 13537.5 716.2 730.5 736.3 746.1 753.2 5458.4 5883.0 6286.3 6473.8 6611.6 81420.6 83720.6 84496.6 85614.0 88573.0 74454.0 74686.3 76100.0 77575.2 78162.6 67551.7 67647.3 68145.7 68524.7 70018.9 12668.8 13268.5 13380.7 13383.2 13389.1 34364.3 34537.0 34679.0 35038.6 35763.8 43116.4 43286.6 43466.0 43753.3 45515.0 14736.1 15549.3 15833.7 17412.4 17803.6 19041.4 19587.9 19669.9 19689.0 20182.0 17839.4 17855.6 18299.4 18422.8 18966.5 16975.2 18302.3 18384.3 18417.7 18808.2 16361.2 16492.2 16500.3 16902.2 18084.6 12253.9 12430.6 12493.4 12704.2 12859.8 14790.6 14919.7 15002.5 15208.8 15261.4 21:41:15 INFO - PID 9573 | -------- Summary: end -------- 21:41:15 INFO - PID 9573 | 21:41:15 INFO - TEST-INFO | 9573: exit 0 21:41:15 INFO - TEST-OK | dromaeo_css | took 1114194ms 21:41:15 INFO - TEST-START | kraken 21:41:15 INFO - operating with platform_type : linux_ 21:41:15 INFO - Initialising browser for kraken test... 21:41:15 INFO - TEST-INFO | started process 9992 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpR7PZc_/profile http://localhost:35786/getInfo.html) 21:41:19 INFO - PID 9992 | __metrics Screen width/height:1600/1200 21:41:19 INFO - PID 9992 | colorDepth:24 21:41:19 INFO - PID 9992 | Browser inner width/height: 1024/695 21:41:19 INFO - PID 9992 | __metrics 21:41:21 INFO - TEST-INFO | 9992: exit 0 21:41:21 INFO - Browser initialized. 21:41:21 INFO - Running cycle 1/1 for kraken test... 21:41:21 INFO - TEST-INFO | started process 10164 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpR7PZc_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:41:33 INFO - PID 10164 | RSS: Main: 156594176 21:41:33 INFO - PID 10164 | 21:41:36 INFO - PID 10164 | Cycle 1(1): loaded http://localhost:35786/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35786/tests/kraken/driver.html?testName=audio-beat-detection) 21:41:37 INFO - PID 10164 | RSS: Main: 162840576 21:41:37 INFO - PID 10164 | 21:41:39 INFO - PID 10164 | Cycle 1(1): loaded http://localhost:35786/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35786/tests/kraken/driver.html?testName=audio-dft) 21:41:40 INFO - PID 10164 | RSS: Main: 161230848 21:41:40 INFO - PID 10164 | 21:41:43 INFO - PID 10164 | Cycle 1(1): loaded http://localhost:35786/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35786/tests/kraken/driver.html?testName=audio-fft) 21:41:43 INFO - PID 10164 | RSS: Main: 156835840 21:41:43 INFO - PID 10164 | 21:41:45 INFO - PID 10164 | Cycle 1(1): loaded http://localhost:35786/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35786/tests/kraken/driver.html?testName=audio-oscillator) 21:41:46 INFO - PID 10164 | RSS: Main: 157949952 21:41:46 INFO - PID 10164 | 21:41:48 INFO - PID 10164 | Cycle 1(1): loaded http://localhost:35786/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35786/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:41:49 INFO - PID 10164 | RSS: Main: 157708288 21:41:49 INFO - PID 10164 | 21:41:52 INFO - PID 10164 | Cycle 1(1): loaded http://localhost:35786/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35786/tests/kraken/driver.html?testName=imaging-darkroom) 21:41:52 INFO - PID 10164 | RSS: Main: 162795520 21:41:52 INFO - PID 10164 | 21:41:56 INFO - PID 10164 | Cycle 1(1): loaded http://localhost:35786/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35786/tests/kraken/driver.html?testName=imaging-desaturate) 21:41:56 INFO - PID 10164 | RSS: Main: 164945920 21:41:56 INFO - PID 10164 | 21:41:59 INFO - PID 10164 | Cycle 1(1): loaded http://localhost:35786/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35786/tests/kraken/driver.html?testName=json-parse-financial) 21:42:00 INFO - PID 10164 | RSS: Main: 164474880 21:42:00 INFO - PID 10164 | 21:42:01 INFO - PID 10164 | Cycle 1(1): loaded http://localhost:35786/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35786/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:42:02 INFO - PID 10164 | RSS: Main: 163979264 21:42:02 INFO - PID 10164 | 21:42:03 INFO - PID 10164 | Cycle 1(1): loaded http://localhost:35786/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35786/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:42:04 INFO - PID 10164 | RSS: Main: 164433920 21:42:04 INFO - PID 10164 | 21:42:06 INFO - PID 10164 | Cycle 1(1): loaded http://localhost:35786/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35786/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:42:07 INFO - PID 10164 | RSS: Main: 164663296 21:42:07 INFO - PID 10164 | 21:42:09 INFO - PID 10164 | Cycle 1(1): loaded http://localhost:35786/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35786/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:42:10 INFO - PID 10164 | RSS: Main: 164421632 21:42:10 INFO - PID 10164 | 21:42:13 INFO - PID 10164 | Cycle 1(1): loaded http://localhost:35786/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35786/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:42:14 INFO - PID 10164 | RSS: Main: 164818944 21:42:14 INFO - PID 10164 | 21:42:15 INFO - PID 10164 | Cycle 1(1): loaded http://localhost:35786/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35786/tests/kraken/driver.html?testName=ai-astar) 21:42:16 INFO - PID 10164 | RSS: Main: 166846464 21:42:16 INFO - PID 10164 | 21:42:16 INFO - PID 10164 | __start_tp_report 21:42:16 INFO - PID 10164 | _x_x_mozilla_page_load 21:42:16 INFO - PID 10164 | _x_x_mozilla_page_load_details 21:42:16 INFO - PID 10164 | |i|pagename|runs| 21:42:16 INFO - PID 10164 | |0;ai-astar;102;111;111;110;110;110;109;110;110;111 21:42:16 INFO - PID 10164 | |1;audio-beat-detection;144;119;124;175;119;119;120;118;141;118 21:42:16 INFO - PID 10164 | |2;audio-dft;162;162;164;164;163;163;165;168;165;163 21:42:16 INFO - PID 10164 | |3;audio-fft;74;74;74;105;75;74;74;74;96;74 21:42:16 INFO - PID 10164 | |4;audio-oscillator;82;81;103;81;81;82;92;81;82;103 21:42:16 INFO - PID 10164 | |5;imaging-gaussian-blur;115;115;114;113;115;113;114;114;114;114 21:42:16 INFO - PID 10164 | |6;imaging-darkroom;148;149;148;149;147;148;149;149;147;149 21:42:16 INFO - PID 10164 | |7;imaging-desaturate;95;95;94;94;95;95;95;95;95;95 21:42:16 INFO - PID 10164 | |8;json-parse-financial;46;45;45;46;47;48;49;47;45;46 21:42:16 INFO - PID 10164 | |9;json-stringify-tinderbox;54;53;53;54;53;53;54;53;54;53 21:42:16 INFO - PID 10164 | |10;stanford-crypto-aes;82;83;83;81;82;81;82;82;80;82 21:42:16 INFO - PID 10164 | |11;stanford-crypto-ccm;125;132;127;135;124;129;132;133;169;129 21:42:16 INFO - PID 10164 | |12;stanford-crypto-pbkdf2;182;184;197;185;183;183;186;194;183;180 21:42:16 INFO - PID 10164 | |13;stanford-crypto-sha256-iterative;65;68;71;67;64;66;65;66;66;63 21:42:16 INFO - PID 10164 | __end_tp_report 21:42:16 INFO - PID 10164 | __start_cc_report 21:42:16 INFO - PID 10164 | _x_x_mozilla_cycle_collect,743 21:42:16 INFO - PID 10164 | __end_cc_report 21:42:16 INFO - PID 10164 | __startTimestamp1493872936118__endTimestamp 21:42:16 INFO - PID 10164 | 21:42:16 INFO - PID 10164 | ------- Summary: start ------- 21:42:16 INFO - PID 10164 | Number of tests: 14 21:42:16 INFO - PID 10164 | 21:42:16 INFO - PID 10164 | [#0] ai-astar Cycles:10 Average:109.40 Median:110.00 stddev:2.67 (2.4%) stddev-sans-first:0.67 21:42:16 INFO - PID 10164 | Values: 102.0 111.0 111.0 110.0 110.0 110.0 109.0 110.0 110.0 111.0 21:42:16 INFO - PID 10164 | 21:42:16 INFO - PID 10164 | [#1] audio-beat-detection Cycles:10 Average:129.70 Median:122.00 stddev:18.64 (15.3%) stddev-sans-first:19.04 21:42:16 INFO - PID 10164 | Values: 144.0 119.0 124.0 175.0 119.0 119.0 120.0 118.0 141.0 118.0 21:42:16 INFO - PID 10164 | 21:42:16 INFO - PID 10164 | [#2] audio-dft Cycles:10 Average:163.90 Median:164.00 stddev:1.79 (1.1%) stddev-sans-first:1.76 21:42:16 INFO - PID 10164 | Values: 162.0 162.0 164.0 164.0 163.0 163.0 165.0 168.0 165.0 163.0 21:42:16 INFO - PID 10164 | 21:42:16 INFO - PID 10164 | [#3] audio-fft Cycles:10 Average:79.40 Median:74.00 stddev:11.33 (15.3%) stddev-sans-first:11.84 21:42:16 INFO - PID 10164 | Values: 74.0 74.0 74.0 105.0 75.0 74.0 74.0 74.0 96.0 74.0 21:42:16 INFO - PID 10164 | 21:42:16 INFO - PID 10164 | [#4] audio-oscillator Cycles:10 Average:86.80 Median:81.50 stddev:9.16 (11.2%) stddev-sans-first:9.55 21:42:16 INFO - PID 10164 | Values: 82.0 81.0 103.0 81.0 81.0 82.0 92.0 81.0 82.0 103.0 21:42:16 INFO - PID 10164 | 21:42:16 INFO - PID 10164 | [#5] imaging-gaussian-blur Cycles:10 Average:114.10 Median:114.00 stddev:0.74 (0.6%) stddev-sans-first:0.71 21:42:16 INFO - PID 10164 | Values: 115.0 115.0 114.0 113.0 115.0 113.0 114.0 114.0 114.0 114.0 21:42:16 INFO - PID 10164 | 21:42:16 INFO - PID 10164 | [#6] imaging-darkroom Cycles:10 Average:148.30 Median:149.00 stddev:0.82 (0.6%) stddev-sans-first:0.87 21:42:16 INFO - PID 10164 | Values: 148.0 149.0 148.0 149.0 147.0 148.0 149.0 149.0 147.0 149.0 21:42:16 INFO - PID 10164 | 21:42:16 INFO - PID 10164 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 21:42:16 INFO - PID 10164 | Values: 95.0 95.0 94.0 94.0 95.0 95.0 95.0 95.0 95.0 95.0 21:42:16 INFO - PID 10164 | 21:42:16 INFO - PID 10164 | [#8] json-parse-financial Cycles:10 Average:46.40 Median:46.50 stddev:1.35 (2.9%) stddev-sans-first:1.42 21:42:16 INFO - PID 10164 | Values: 46.0 45.0 45.0 46.0 47.0 48.0 49.0 47.0 45.0 46.0 21:42:16 INFO - PID 10164 | 21:42:16 INFO - PID 10164 | [#9] json-stringify-tinderbox Cycles:10 Average:53.40 Median:53.50 stddev:0.52 (1.0%) stddev-sans-first:0.50 21:42:16 INFO - PID 10164 | Values: 54.0 53.0 53.0 54.0 53.0 53.0 54.0 53.0 54.0 53.0 21:42:16 INFO - PID 10164 | 21:42:16 INFO - PID 10164 | [#10] stanford-crypto-aes Cycles:10 Average:81.80 Median:82.00 stddev:0.92 (1.1%) stddev-sans-first:0.97 21:42:16 INFO - PID 10164 | Values: 82.0 83.0 83.0 81.0 82.0 81.0 82.0 82.0 80.0 82.0 21:42:16 INFO - PID 10164 | 21:42:16 INFO - PID 10164 | [#11] stanford-crypto-ccm Cycles:10 Average:133.50 Median:132.00 stddev:12.96 (9.8%) stddev-sans-first:13.38 21:42:16 INFO - PID 10164 | Values: 125.0 132.0 127.0 135.0 124.0 129.0 132.0 133.0 169.0 129.0 21:42:16 INFO - PID 10164 | 21:42:16 INFO - PID 10164 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.70 Median:184.50 stddev:5.46 (3.0%) stddev-sans-first:5.62 21:42:16 INFO - PID 10164 | Values: 182.0 184.0 197.0 185.0 183.0 183.0 186.0 194.0 183.0 180.0 21:42:16 INFO - PID 10164 | 21:42:16 INFO - PID 10164 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.10 Median:66.00 stddev:2.23 (3.4%) stddev-sans-first:2.33 21:42:16 INFO - PID 10164 | Values: 65.0 68.0 71.0 67.0 64.0 66.0 65.0 66.0 66.0 63.0 21:42:16 INFO - PID 10164 | -------- Summary: end -------- 21:42:16 INFO - PID 10164 | 21:42:17 INFO - TEST-INFO | 10164: exit 0 21:42:17 INFO - TEST-OK | kraken | took 61342ms 21:42:17 INFO - SUITE-END | took 1175s 21:42:17 INFO - Completed test suite (00:19:35) 21:42:17 INFO - javascript benchmark 21:42:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7722.339613353415, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3327.9500807487884, 3337.2324365395957, 3348.4140275451796, 3370.342783157282, 3370.646082161373, 5601.327840659116, 5694.658320500773, 5709.115087161486, 5759.712014399279, 5764.740586673599, 5690.2033715279895, 5697.886042279152, 5715.77136914524, 5788.334341550723, 5834.5915785895, 5042.974785126087, 5100.948990510103, 5107.897842043157, 5114.0, 5114.232865070232, 5143.125668636322, 5179.249008893708, 5197.922031169535, 5204.661696989683, 5210.114280572312, 5062.54437100659, 5103.2855400244, 5133.050385678662, 5138.177891537336, 5164.793408263665, 5086.872828179288, 5088.313077734532, 5093.057784309877, 5108.386993560759, 5159.045576568347, 5046.495350464924, 5102.872428189325, 5103.387593488805, 5109.8722531937, 5118.667286626357, 5603.971980140114, 5621.971890140564, 5672.376038635747, 5735.598508104435, 5761.53907687384, 6184.07238914162, 6196.597221180654, 6215.782447614311, 6219.253689557237, 6232.626042437469, 6159.3224745277985, 6244.87510249797, 6245.375462453764, 6246.187995560594, 6261.029540421242, 5673.744681489343, 5740.712964351849, 5750.856228594319, 5824.242848429677, 5835.153902684087, 6340.080688300171, 6357.237131544244, 6362.331955144643, 6375.0, 6376.29860715321, 5930.673812940329, 5943.405659434022, 6047.909281360783, 6095.66473843934, 6110.694465276719, 6218.347073557302, 6220.129181914535, 6222.066689996446, 6225.750969961151, 6238.781642642486, 6077.878442431127, 6124.846878827976, 6143.56995010354, 6147.170132032208, 6205.069239614004, 6157.568970172135, 6213.03697926822, 6229.657368844666, 6239.939210334254, 6242.1261023456755, 4727.763611819395, 4738.0523895221095, 4772.522747725269, 4801.639877009168, 4809.44691360491, 4186.790660466964, 4214.3678448232395, 4244.193603215379, 4260.105377870682, 4268.487781466244, 5912.408759124053, 5915.970420147958, 5946.672932988728, 5964.224650795456, 5980.342162362137, 6165.876682466415, 6212.192414986023, 6212.937870621236, 6214.285357183891, 6214.471769899519, 5742.454466825645, 5875.706214689249, 5916.615419997686, 5919.260092488439, 5936.7328470218945, 5329.680219186784, 5345.091334473149, 5362.597805164628, 5366.91949620756, 5394.298741163623, 6446.613203207822, 6446.7421303148285, 6454.225492940784, 6580.276169621242, 6581.638009909309, 6179.0, 6188.783392581418, 6197.287311959002, 6198.380161983946, 6217.533684973555, 6466.353364663496, 6517.250516190698, 6532.281449040506, 6535.313792051957, 6543.312952140147, 6021.7591296347655, 6040.516758659384, 6097.7865774697675, 6118.60229085108, 6124.6325220486915, 6050.485708714808, 6114.724837382418, 6156.199694039746, 6187.84530386744, 6210.813675589739, 5935.228420305332, 5983.670898100472, 5992.161097446448, 5994.880102398015, 6010.4891084258325, 5979.790707325223, 5982.341942386421, 5984.1921340618455, 6003.549733769897, 6007.909881351696, 5918.171455996078, 5972.850678733066, 5973.044312909914, 5982.551308651781, 5989.580729348903, 5985.371535988833, 5989.251343582052, 6033.155358249936, 6036.8188954331445, 6047.244094488189, 5843.474087332161, 5865.472107510344, 5866.413358664099, 5866.914620795166, 5900.616459930091], "value": 5678.753111563195, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [814.2264848394025, 834.182501148874, 1126.6563698072084, 1139.5384869128006, 1220.4568966809761, 35967.820160899195, 38151.236975260515, 38167.236655266875, 38329.61670383295, 38350.808245958804, 35783.284334313365, 36199.638003619955, 36215.275694486096, 36292.45561316576, 36331.273374532495, 6825.897611535831, 6956.860862782742, 6961.23426423093, 6969.128858892638, 7247.057882475271, 7354.264573542657, 7484.101907771075, 7499.437542184331, 7503.474756767028, 7515.887261691052, 7416.0729908761405, 7510.399168066541, 7524.0, 7527.435442341846, 7540.095188577378, 7428.368588669916, 7469.813254668622, 7488.363489103433, 7492.100947886262, 7495.775126746206, 7275.1633562140505, 7280.890786638205, 7287.817804554876, 7313.853722925565, 7337.41300695943, 36685.633143668485, 37077.814610927046, 37214.0, 37217.25565488702, 37304.62695373066, 3488.9825550872083, 3516.542849429558, 3518.665726755954, 3522.630123836986, 3525.8413371398346, 29015.709842901513, 29180.562291565642, 29347.41305173906, 29409.11772646824, 29411.705882941325, 3754.305453491113, 3770.453284273792, 3773.660370566662, 3794.5636251831115, 3798.0504873781556, 194944.0, 195489.0, 195505.0, 196456.99999999857, 197649.0, 45125.548744512795, 47517.52482475131, 47814.043719126115, 48353.0, 48424.273635895486, 49537.25694114591, 49854.50145498499, 49889.75055124774, 49892.750536247084, 50066.49933500691, 14722.337494812758, 14752.852471475362, 14853.48012819546, 14921.179335136454, 14927.0, 11599.0, 11659.300441973508, 11717.414129293502, 11725.179237453296, 11776.58781942645, 14339.856601433852, 14362.928185359006, 14418.495352662607, 14476.27618619065, 14570.344334504973, 5594.888102237933, 5712.0, 5720.11338242573, 5740.913886291709, 5764.117941029485, 1091.1979694924225, 1117.8267368557886, 1125.6904351303294, 1128.4808987865674, 1139.1968662093204, 25513.851876665594, 26330.0, 26542.336441589116, 26556.203313900613, 26656.60015099775, 1183.2722875431548, 1194.7431302270054, 1209.0, 1217.3183017510223, 1227.2329793878826, 13650.522231721841, 13850.030497865053, 16974.83025169757, 17506.912465437847, 17808.732869006973, 271665.0, 272045.0, 273172.0, 275237.0, 276116.0, 57598.7120064397, 57858.710706446196, 57890.710546447, 57893.42106578965, 58058.0, 1448.8551144885637, 1475.6532214929482, 1492.2165862922052, 1510.8489151085023, 1518.9012714173764, 28215.0, 29661.0, 30117.247068823453, 30443.695563044086, 30900.22749431282, 38839.80580097081, 39029.21941561096, 39083.2183356337, 39099.0, 39271.214575708895, 225367.0, 226406.0, 226810.0, 227694.0, 227967.0, 33283.33433331368, 34384.656153439144, 35269.64730352664, 35813.641863582074, 36007.81996090003, 42166.15667686691, 43028.56971430371, 45303.54696452993, 45811.77094114642, 46000.309995349424, 32116.19709507281, 32435.18912027218, 32718.0, 33594.88723603723, 33884.152896177795, 32649.34701306008, 32976.505352419255, 33051.83474082711, 33933.830330848185, 34239.315213696085, 38444.42333364946, 38474.230515388976, 38717.0, 38900.610993890834, 38961.805190975, 30625.846870765505, 30647.693523064485, 30661.0, 31488.0, 33142.337153257286, 24949.625755614044, 26054.60918086268, 26204.73795262023, 26225.868870655526, 26453.0], "value": 17488.62715637643, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [438.99561004389966, 448.6769525941322, 448.77561219390304, 457.09723296489426, 465.88352911772057, 20086.89956550217, 21049.789502104974, 21425.0, 21478.57042859144, 21661.35015949524, 22087.66868496974, 22349.553008939813, 22725.9773310201, 22757.544849103007, 22891.427714307152, 4392.77131308488, 5499.312585926759, 5677.148427735852, 5681.3466451358, 5688.943110568904, 5511.724413779315, 5624.718764061801, 5631.60578759487, 5634.605577609569, 5638.548916086703, 5381.515663590276, 5508.338999320087, 5584.497395234428, 5589.888202235953, 5612.635178713371, 5556.444355564452, 5565.41563135871, 5582.078956972095, 5582.804601838916, 5599.076152434844, 5018.64869459138, 5023.648344615878, 5044.47033061527, 5047.899042019139, 5059.203982586166, 25666.358341041436, 25786.48427031449, 25887.0, 26058.0, 26291.07981220648, 2151.505153814616, 2170.804627583509, 2191.9780802192095, 2194.868307901531, 2195.1548653768255, 16912.66174676512, 17191.91404042984, 17344.392946246822, 17490.825091749048, 17687.0, 2174.5868285025795, 2204.1954686539298, 2270.080617349976, 2287.3824067501682, 2289.301762962296, 107871.46064269629, 108600.0, 111524.88475115226, 111686.44156779246, 113413.43293283564, 12047.337396443105, 12061.216020958609, 12510.49958001688, 12528.81206781899, 12624.431900564497, 16894.0, 16951.49145525636, 16964.406245781338, 17039.744403833953, 17069.06120163378, 8312.33501319905, 8590.914090859136, 8678.392512524062, 8845.380823342304, 8880.111988801069, 7827.608619569112, 7846.450748447553, 8004.91933588958, 8131.715389961323, 8162.510249385056, 7798.958406221578, 7827.608619569112, 7999.720009799629, 8086.555239461768, 8095.7166499172245, 2254.9098036078703, 2269.795718385353, 2276.1236923784463, 2289.439087423592, 2463.2856471623027, 998.8202123617673, 1013.4932533733133, 1056.793925184597, 1066.0831684751108, 1079.8272276435732, 19839.0, 20364.185432582828, 20852.687209691867, 21005.68491472629, 21289.78710212909, 1077.0306723948509, 1081.1837063017529, 1101.658485869383, 1110.4947249001684, 1120.5293776613842, 10882.932242873829, 11352.148588855704, 11353.37556434404, 11361.997722628048, 11386.829197562043, 488480.0, 503469.0, 504519.0, 516421.0, 532157.0, 91582.54208728914, 92622.53688731648, 95575.0, 96335.51832240794, 100116.49941750245, 1258.4840215511597, 1259.5339724302066, 1308.8822006019504, 1313.6847156682327, 1324.0996122636507, 20909.26817561378, 21120.894395527925, 21672.133114675238, 21875.343739687836, 21901.67147492757, 45083.0, 45499.0, 46607.0, 46726.766366167954, 47212.52787472219, 58701.0, 59671.13781915135, 59799.402005979384, 60338.69830650819, 60593.0911036326, 9410.341276110606, 9580.856287155837, 9677.516124193622, 9778.168855647065, 9863.933715640966, 14031.859681403055, 14271.215083170526, 14336.713265734836, 14603.634909126931, 15543.222838858239, 12553.560625378068, 12987.090903636654, 13157.60527184186, 13169.802452963404, 13358.064935454426, 11657.88342116602, 11779.234349767237, 12149.574764883188, 12151.453184606891, 12190.51237950472, 12003.578979990662, 12502.624921252376, 12675.93662031684, 12698.0, 13530.255835928725, 9357.906420935704, 9541.427514349161, 9640.855387169338, 9719.319647624881, 9726.513674316397, 12997.740045199233, 13425.127366721132, 13945.511907083204, 14173.574792756233, 14338.56984290473], "value": 10249.50114998657, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1309.5791066692639, 1358.6399604104909, 1363.4546181527382, 1373.1417863835104, 1376.4150236149637, 10520.78958420831, 10885.510152043158, 10949.397783121925, 11059.115270778339, 11256.943715281433, 11053.392063436508, 11158.609448669298, 11183.888161118386, 11185.32888026719, 11198.328100313987, 2180.35679474555, 2209.039068005423, 2218.0684112672634, 2219.9334019979347, 2222.5777102350585, 2202.1081462007915, 2267.399139228106, 2273.977260227393, 2274.6133157363206, 2275.7838005389544, 2256.0975609756147, 2277.2826559633663, 2306.3657494189065, 2317.1079134533243, 2320.5938960681815, 2164.231697747293, 2174.402039439159, 2216.3683350245183, 2222.5443784024237, 2222.9110835566476, 2151.085788539864, 2176.9020394082304, 2179.574982878339, 2288.279192054514, 2313.3869524576003, 10881.075108615778, 10924.399158046304, 10984.725381865437, 10987.066099381562, 11009.724756881062, 6439.517036222255, 6670.933290667128, 6673.833154171136, 6689.23073846509, 6690.293975036383, 3798.924021519554, 3871.2257548490416, 3905.1799122184093, 4057.675385969145, 4149.543550209474, 1273.3887733887693, 1349.8920086393066, 1409.2953523238382, 1458.4166333466592, 1475.6901050779243, 15275.083494990336, 15291.847081529264, 15322.15728134952, 15386.692266154727, 15391.538253852403, 5158.4067832199025, 5169.116388270654, 5339.519443250048, 5418.485243901823, 5543.722813859291, 11299.378534180534, 11343.43282835855, 11391.601293954673, 11394.373309467894, 11452.54189832414, 6283.277423096311, 6371.52213583983, 6387.680615969183, 6396.040593910858, 6412.326705695835, 5884.852878678067, 6059.394060593993, 6069.878602427927, 6135.601185922901, 6142.140100385949, 4129.359949207877, 4131.5455299916985, 4206.305959516645, 4217.557156498524, 4286.549912259168, 1231.5221733919332, 1242.27327013698, 1255.535451882791, 1257.5535684831937, 1265.2218885385605, 874.6326542852017, 897.8608315711075, 905.158202871336, 907.5507623726297, 916.0564618443015, 6526.510511711641, 6549.803505894831, 6558.5409021369, 6679.532432729662, 6680.198376194888, 645.7287939065603, 646.6055706019324, 647.3170804800886, 652.6801867085189, 659.5416185750857, 4033.1328764315813, 4143.399207114951, 4366.956330436718, 4396.230659634552, 4424.6902716810155, 13816.516421925184, 14269.643258918402, 14292.071015384176, 14416.207108608918, 14437.927810360881, 13316.800247996287, 14208.431662733581, 14225.0, 14230.501932432315, 14250.002499824912, 8887.422317549339, 9018.143276388733, 9039.593218305192, 9047.366684332164, 9078.273738101068, 6728.226253980855, 6914.55055421396, 7007.964960175166, 7092.468064895257, 7128.28717128283, 12330.445129968994, 12470.189437686522, 12609.684757881127, 12781.04142189358, 12818.0, 12534.185277956904, 12567.497300107892, 12972.805407919077, 13070.673233168867, 13088.0, 9175.22010629104, 9339.889379107039, 9378.343515954091, 9402.200812930978, 9411.95294023553, 10563.735906602396, 10691.09125724322, 10735.516901739284, 10742.3017503862, 10753.569857205624, 8975.640974360953, 9165.404248723811, 9208.585613647536, 9367.906320936703, 9422.387544809566, 9145.131212534798, 9184.08159184103, 9239.39943903644, 9288.210502107397, 9516.85724714116, 8272.627731752205, 8304.0, 8358.080611132893, 8451.0, 8460.450070745383, 6229.875402492015, 6235.96882015587, 6260.874782504416, 6492.285848556749, 6521.184851893513, 6452.387023232785, 6452.419282264619, 6455.2576453708425, 6627.768028118992, 6686.5653732507235], "value": 5222.896655923257, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [418.6713429957483, 421.7933212725764, 425.58718043497834, 429.0732018839308, 432.6863024307378, 5942.405759424056, 6068.150458935753, 6077.635341879491, 6084.72618732157, 6099.57302988791, 5844.707764611763, 5918.585699001072, 5922.200502932103, 5931.839299809172, 5970.253718285215, 4350.738955662655, 4437.778111094449, 4461.598456138947, 4469.687121901468, 4472.2173619616515, 4432.534583868695, 4441.600255976961, 4454.844080457185, 4458.9331160032625, 4467.352233926079, 4414.933775993362, 4436.489803672571, 4441.222786012451, 4444.377787109807, 4445.51099379068, 4371.125774845043, 4413.492448368446, 4442.067165895166, 4444.022315090707, 4456.420665313521, 3836.251930873481, 3848.4034974578985, 3853.421986702017, 3856.2480316338324, 3856.5179352580926, 5788.913166302509, 5843.620164689281, 5845.165094928956, 5846.473817356458, 5856.941430585724, 5121.180611102243, 5201.271821944931, 5226.242194881721, 5247.160454327328, 5252.0, 6288.685565721696, 6304.0, 6309.589876658002, 6311.431971122575, 6321.336259692758, 4272.679549033804, 4349.652027837766, 4350.695451318409, 4352.36890650857, 4366.519682834917, 6344.809655710336, 6584.407403333723, 6614.735410583571, 6614.86770264592, 6664.500162487833, 4391.275439552502, 4414.911701766011, 4415.4259946206785, 4426.667999900021, 4433.8226470941445, 4468.218061839165, 4477.925297928474, 4483.19302525542, 4494.191045611821, 4512.165249428866, 5131.897362052738, 5191.6625419348375, 5207.114790485626, 5230.633855630145, 5250.97374513125, 6251.249850018027, 6305.621662700252, 6316.178896743486, 6337.96831015842, 6378.617282963037, 1523.2764436892387, 1537.5239769820873, 1544.1970175508673, 1546.5360391882616, 1546.8839837012044, 1010.2927950434726, 1033.3696445168441, 1034.255336157965, 1037.0407373179778, 1045.2056437107854, 6140.539459540463, 6152.0, 6159.583295841981, 6164.321924588292, 6213.627182369072, 6358.618482891041, 6470.835249655013, 6480.157579514727, 6518.902216466757, 6527.640979746065, 6090.878182436327, 6116.898958187569, 6118.969405152946, 6132.41742034506, 6137.294211165773, 3371.4268574342423, 3453.827308634558, 3456.1705190754037, 3456.716119686308, 3471.392506311385, 6647.5014373921185, 6816.0, 6825.590464572042, 6874.243833178346, 6877.0, 6598.769043083469, 6620.139381880324, 6656.234533028667, 6673.366030227121, 6712.160979877515, 6603.306652801482, 6779.423748981392, 6866.793996180023, 6875.5530890491955, 6877.724891004404, 6443.226812782309, 6479.783774061925, 6514.674266286762, 6545.050967609574, 6545.476361891133, 6384.904226436514, 6397.744090236524, 6464.2889282177985, 6491.772787952399, 6506.0, 6738.663066846736, 6907.585544867324, 6916.723331066701, 6937.445004399535, 6963.79108626742, 4440.20362589981, 4450.376947227455, 4455.108978204307, 4467.195904737178, 4472.865814025455, 6511.0, 6533.705983230671, 6568.113304703995, 6581.276059633341, 6619.3380661933425, 6324.905126423016, 6349.77775777864, 6368.808935731936, 6372.394622510854, 6386.584871983307, 6267.749290028531, 6291.748330066746, 6326.746930122928, 6393.424591786761, 6408.487321014214, 6313.747450102128, 6350.333215012357, 6371.49028077762, 6379.553431259953, 6393.2647745508, 6255.841237646411, 6385.425311721968, 6397.96801015992, 6427.550071495138, 6464.224293084671, 6188.319284878749, 6204.224471941007, 6214.161088253028, 6225.31521532622, 6225.719842607003], "value": 4779.703059862697, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [850.1948772322519, 875.1773333066917, 875.5447167472918, 883.2095274729118, 899.1323372945106, 13836.654083647914, 14341.56975290743, 15565.766513502307, 15997.120158391284, 16062.0, 15940.362385504566, 16645.417410390608, 16815.243314050866, 17035.403760868372, 17161.14194290287, 1016.9694909152719, 1043.6608102366724, 1045.9424731639756, 1047.2669131607868, 1047.287844265899, 1022.5091955861191, 1023.3603997501561, 1026.112412762962, 1026.9024442677971, 1028.4960369418968, 1075.4891426572356, 1075.6826736112826, 1075.8655168103987, 1082.783443311337, 1097.1497089755424, 1077.6874706335154, 1085.696005118568, 1087.1954753482446, 1088.086007753487, 1090.7545802194522, 793.8491686579531, 793.8690116130831, 795.3716563914559, 796.0765512006103, 796.52606699014, 15449.0, 15760.211989400485, 15886.602834929103, 16014.839851601568, 16023.11872846993, 18055.638887222314, 18187.27250909962, 18299.634007319914, 18305.908470457696, 18438.354657587053, 14008.439662413492, 14035.1578905265, 14089.0, 14093.365798539091, 14121.0, 3180.6183258008955, 3183.4588120019653, 3211.8554665039896, 3226.161198088514, 3226.209578653245, 93805.0, 95341.0, 96078.0, 96787.0, 97280.51359743226, 12535.749285014248, 12726.0, 12889.806652900214, 12984.610461686165, 13017.153884997444, 21704.131834726435, 21873.781262187174, 22032.11871525153, 22076.88961555182, 22083.889580551993, 8401.411901166859, 8645.95677021611, 8729.956350218336, 8738.995015573164, 8744.519051452231, 3879.1078052048188, 3926.1951299983434, 3927.155661532784, 3928.0769019280447, 3951.68386529077, 4511.70673906195, 4658.767061646904, 4675.649326300541, 4693.859184224479, 4717.740524271129, 921.4056933277998, 944.4097439100562, 945.7162851144767, 955.694177863091, 959.9568019439142, 711.434409644334, 713.3437237741291, 720.9134903811577, 721.6463932672953, 732.5092188233896, 11425.485853136628, 12907.354632268349, 13202.0, 13503.86496135026, 13537.458501659823, 716.1799739298474, 730.4886579394445, 736.2600586410623, 746.138210367027, 753.1865585168115, 5458.426865179178, 5883.0, 6286.308506064329, 6473.805785826433, 6611.570247933869, 81420.59289703514, 83720.58139709262, 84496.57751711202, 85614.0, 88573.0, 74454.0, 74686.25313746902, 76100.0, 77575.22424775793, 78162.6091869537, 67551.66224168848, 67647.32352676509, 68145.65927170332, 68524.6573767128, 70018.94971575431, 12668.809967850306, 13268.469261229442, 13380.665483362995, 13383.19700817954, 13389.062765606313, 34364.31271374608, 34537.0, 34679.0, 35038.64961350354, 35763.82118089393, 43116.353254700574, 43286.56713432951, 43466.0, 43753.343699845165, 45515.0, 14736.115833050051, 15549.300281487587, 15833.68332633364, 17412.39056633012, 17803.554911127325, 19041.42875713731, 19587.902060489607, 19669.901650491654, 19689.0, 20181.990900455214, 17839.375621853174, 17855.553611159306, 18299.359522416653, 18422.81577184211, 18966.52583685419, 16975.23611437513, 18302.26790928348, 18384.264629415207, 18417.723734143732, 18808.247670093042, 16361.181940902668, 16492.17539123015, 16500.33998640089, 16902.154892255585, 18084.553235741372, 12253.938730306649, 12430.564930227398, 12493.375331233221, 12704.174228675107, 12859.807102893652, 14790.556283311518, 14919.701605968037, 15002.474913377979, 15208.771868421762, 15261.389544418544], "value": 8345.210595640841, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1493.2999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 111.0, 111.0, 110.0, 110.0, 110.0, 109.0, 110.0, 110.0, 111.0], "value": 109.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 119.0, 124.0, 175.0, 119.0, 119.0, 120.0, 118.0, 141.0, 118.0], "value": 129.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 164.0, 164.0, 163.0, 163.0, 165.0, 168.0, 165.0, 163.0], "value": 163.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 74.0, 105.0, 75.0, 74.0, 74.0, 74.0, 96.0, 74.0], "value": 79.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 103.0, 81.0, 81.0, 82.0, 92.0, 81.0, 82.0, 103.0], "value": 86.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 115.0, 114.0, 113.0, 115.0, 113.0, 114.0, 114.0, 114.0, 114.0], "value": 114.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 149.0, 148.0, 149.0, 147.0, 148.0, 149.0, 149.0, 147.0, 149.0], "value": 148.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [46.0, 45.0, 45.0, 46.0, 47.0, 48.0, 49.0, 47.0, 45.0, 46.0], "value": 46.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 53.0, 54.0, 53.0, 53.0, 54.0, 53.0, 54.0, 53.0], "value": 53.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 83.0, 81.0, 82.0, 81.0, 82.0, 82.0, 80.0, 82.0], "value": 81.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 132.0, 127.0, 135.0, 124.0, 129.0, 132.0, 133.0, 169.0, 129.0], "value": 133.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 184.0, 197.0, 185.0, 183.0, 183.0, 186.0, 194.0, 183.0, 180.0], "value": 185.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 68.0, 71.0, 67.0, 64.0, 66.0, 65.0, 66.0, 66.0, 63.0], "value": 66.1, "unit": "score"}]}]} 21:42:17 INFO - Return code: 0 21:42:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:42:17 INFO - # TBPL SUCCESS # 21:42:17 INFO - Running post-action listener: _resource_record_post_action 21:42:17 INFO - [mozharness: 2017-05-04 04:42:17.937024Z] Finished run-tests step (success) 21:42:17 INFO - Running post-run listener: _resource_record_post_run 21:42:18 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 12288; Write bytes: 356343808; Read time: 244; Write time: 220352 21:42:18 INFO - TinderboxPrint: CPU usage
13.2% 21:42:18 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 244 21:42:18 INFO - TinderboxPrint: I/O write bytes / time
356,343,808 / 220,352 21:42:18 INFO - TinderboxPrint: CPU idle
8,254.1 (86.8%) 21:42:18 INFO - TinderboxPrint: CPU user
1,202.2 (12.6%) 21:42:18 INFO - TinderboxPrint: Swap in / out
0 / 0 21:42:18 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 4096; Write bytes: 107036672; Read time: 80; Write time: 98344 21:42:18 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 4096; Write bytes: 248803328; Read time: 152; Write time: 121976 21:42:18 INFO - Running post-run listener: _upload_blobber_files 21:42:18 INFO - Blob upload gear active. 21:42:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:42:18 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 21:42:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 21:42:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 21:42:18 INFO - (blobuploader) - INFO - Open directory for files ... 21:42:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 21:42:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:42:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:42:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 21:42:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:42:19 INFO - (blobuploader) - INFO - Done attempting. 21:42:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:42:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:42:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:42:22 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:42:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:42:22 INFO - (blobuploader) - INFO - Done attempting. 21:42:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 21:42:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:42:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:42:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 21:42:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:42:23 INFO - (blobuploader) - INFO - Done attempting. 21:42:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 21:42:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:42:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:42:25 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 21:42:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:42:25 INFO - (blobuploader) - INFO - Done attempting. 21:42:25 INFO - (blobuploader) - INFO - Iteration through files over. 21:42:25 INFO - Return code: 0 21:42:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:42:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:42:25 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fbf4c0e3cebcd7bfad2656b85b5e495c84498267b23fd7d5600493ba3fdc40d1febbe2a797b96678d2a336137d597009b98a11b4e6a54016f10be637cd6f41ee", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/baf6d108921295e5f1ba64e6487f0c32dc9b74d5287481a6a21b8118c8e600bc52330006081c128aaac23289e2dfad41d5855ca3420043ed359aa6f755ed7529", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/89790ef64e191d1ffd591381f9f290c3a525cad0470c7242006ab1a8118a38f3b3d1dfbcebdfc57b979a4fcfea07d03e46dc5ea993bff0f84c3f0c2ed5007310", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb238e387f8e33e7446571a0215e3f16e313ecf67c0d0ef8034eb9bfc864369f80c06c94893478692b3a88a7ed894a969aba9eca48b60e06e850f7d42babbb3c"} 21:42:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:42:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:42:25 INFO - Contents: 21:42:25 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fbf4c0e3cebcd7bfad2656b85b5e495c84498267b23fd7d5600493ba3fdc40d1febbe2a797b96678d2a336137d597009b98a11b4e6a54016f10be637cd6f41ee", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/baf6d108921295e5f1ba64e6487f0c32dc9b74d5287481a6a21b8118c8e600bc52330006081c128aaac23289e2dfad41d5855ca3420043ed359aa6f755ed7529", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/89790ef64e191d1ffd591381f9f290c3a525cad0470c7242006ab1a8118a38f3b3d1dfbcebdfc57b979a4fcfea07d03e46dc5ea993bff0f84c3f0c2ed5007310", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb238e387f8e33e7446571a0215e3f16e313ecf67c0d0ef8034eb9bfc864369f80c06c94893478692b3a88a7ed894a969aba9eca48b60e06e850f7d42babbb3c"} 21:42:25 INFO - Running post-run listener: copy_logs_to_upload_dir 21:42:25 INFO - Copying logs to upload dir... 21:42:25 INFO - mkdir: /builds/slave/test/build/upload/logs 21:42:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1246.669562 ========= master_lag: -0.08 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2017-05-03 21:42:24.973436) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-03 21:42:24.981092) ========= 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=db0483c52d987ecb441a263b000001ad-1493868486.264432-1475407895 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fbf4c0e3cebcd7bfad2656b85b5e495c84498267b23fd7d5600493ba3fdc40d1febbe2a797b96678d2a336137d597009b98a11b4e6a54016f10be637cd6f41ee", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/baf6d108921295e5f1ba64e6487f0c32dc9b74d5287481a6a21b8118c8e600bc52330006081c128aaac23289e2dfad41d5855ca3420043ed359aa6f755ed7529", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/89790ef64e191d1ffd591381f9f290c3a525cad0470c7242006ab1a8118a38f3b3d1dfbcebdfc57b979a4fcfea07d03e46dc5ea993bff0f84c3f0c2ed5007310", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb238e387f8e33e7446571a0215e3f16e313ecf67c0d0ef8034eb9bfc864369f80c06c94893478692b3a88a7ed894a969aba9eca48b60e06e850f7d42babbb3c"} build_url:https://queue.taskcluster.net/v1/task/c-MWHmbCRDKsZJWYrhhqaQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011247 build_url: 'https://queue.taskcluster.net/v1/task/c-MWHmbCRDKsZJWYrhhqaQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fbf4c0e3cebcd7bfad2656b85b5e495c84498267b23fd7d5600493ba3fdc40d1febbe2a797b96678d2a336137d597009b98a11b4e6a54016f10be637cd6f41ee", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/baf6d108921295e5f1ba64e6487f0c32dc9b74d5287481a6a21b8118c8e600bc52330006081c128aaac23289e2dfad41d5855ca3420043ed359aa6f755ed7529", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/89790ef64e191d1ffd591381f9f290c3a525cad0470c7242006ab1a8118a38f3b3d1dfbcebdfc57b979a4fcfea07d03e46dc5ea993bff0f84c3f0c2ed5007310", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb238e387f8e33e7446571a0215e3f16e313ecf67c0d0ef8034eb9bfc864369f80c06c94893478692b3a88a7ed894a969aba9eca48b60e06e850f7d42babbb3c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-03 21:42:25.009458) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-03 21:42:25.009795) ========= 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=db0483c52d987ecb441a263b000001ad-1493868486.264432-1475407895 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004439 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-03 21:42:25.091425) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-03 21:42:25.094762) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-03 21:42:25.095105) ========= ========= Total master_lag: 0.08 =========