builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-047 starttime: 1500340321.27 results: success (0) revision: 5e73b9798464c3f7106f0161dc9a49b234f42f9c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-07-17 18:12:01.267364) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-07-17 18:12:01.267781) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-17 18:12:01.268051) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-17 18:12:01.322196) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-17 18:12:01.322483) ========= 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=03627c79f6bb3d1578c4a402000001ba-1500340271.561761-941163189 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-07-17 18:12:01-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.0M=0s 2017-07-17 18:12:01 (46.0 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.398197 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-17 18:12:01.780013) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-17 18:12:01.780314) ========= 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=03627c79f6bb3d1578c4a402000001ba-1500340271.561761-941163189 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018302 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-17 18:12:01.860174) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-07-17 18:12:01.860475) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 5e73b9798464c3f7106f0161dc9a49b234f42f9c --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 5e73b9798464c3f7106f0161dc9a49b234f42f9c --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=03627c79f6bb3d1578c4a402000001ba-1500340271.561761-941163189 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-07-17 18:12:02,159 truncating revision to first 12 chars 2017-07-17 18:12:02,159 Setting DEBUG logging. 2017-07-17 18:12:02,159 attempt 1/10 2017-07-17 18:12:02,159 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/5e73b9798464?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-07-17 18:12:03,646 unpacking tar archive at: mozilla-central-5e73b9798464/testing/mozharness/ program finished with exit code 0 elapsedTime=1.826137 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-07-17 18:12:03.713697) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-17 18:12:03.716047) ========= script_repo_revision: 5e73b9798464c3f7106f0161dc9a49b234f42f9c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-17 18:12:03.716406) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-17 18:12:03.716661) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-17 18:12:03.735262) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2017-07-17 18:12:03.735521) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=03627c79f6bb3d1578c4a402000001ba-1500340271.561761-941163189 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:12:04 INFO - MultiFileLogger online at 20170717 18:12:04 in /builds/slave/test 18:12:04 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 18:12:04 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:12:04 INFO - {'append_to_log': False, 18:12:04 INFO - 'base_work_dir': '/builds/slave/test', 18:12:04 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 18:12:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:12:04 INFO - 'branch': 'Firefox-Non-PGO', 18:12:04 INFO - 'buildbot_json_path': 'buildprops.json', 18:12:04 INFO - 'code_coverage': False, 18:12:04 INFO - 'config_files': ('talos/linux_config.py',), 18:12:04 INFO - 'default_actions': ('clobber', 18:12:04 INFO - 'read-buildbot-config', 18:12:04 INFO - 'download-and-extract', 18:12:04 INFO - 'populate-webroot', 18:12:04 INFO - 'create-virtualenv', 18:12:04 INFO - 'install', 18:12:04 INFO - 'setup-mitmproxy', 18:12:04 INFO - 'run-tests'), 18:12:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:12:04 INFO - 'disable_ccov_upload': False, 18:12:04 INFO - 'download_minidump_stackwalk': True, 18:12:04 INFO - 'download_symbols': 'ondemand', 18:12:04 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:12:04 INFO - 'tooltool.py': '/tools/tooltool.py', 18:12:04 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:12:04 INFO - '/tools/misc-python/virtualenv.py')}, 18:12:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:12:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:12:04 INFO - 'gecko_profile': False, 18:12:04 INFO - 'gecko_profile_interval': 0, 18:12:04 INFO - 'installer_path': 'installer.exe', 18:12:04 INFO - 'log_level': 'info', 18:12:04 INFO - 'log_name': 'talos', 18:12:04 INFO - 'log_to_console': True, 18:12:04 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:12:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:12:04 INFO - 'opt_config_files': (), 18:12:04 INFO - 'pip_index': False, 18:12:04 INFO - 'suite': 'dromaeojs-e10s', 18:12:04 INFO - 'system_bits': '32', 18:12:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:12:04 INFO - 'title': 'talos-linux64-ix-047', 18:12:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:12:04 INFO - 'use_talos_json': True, 18:12:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:12:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:12:04 INFO - 'work_dir': 'build'} 18:12:04 INFO - [mozharness: 2017-07-18 01:12:04.123101Z] Running clobber step. 18:12:04 INFO - Running pre-action listener: _resource_record_pre_action 18:12:04 INFO - Running main action method: clobber 18:12:04 INFO - rmtree: /builds/slave/test/build 18:12:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:12:05 INFO - Running post-action listener: _resource_record_post_action 18:12:05 INFO - [mozharness: 2017-07-18 01:12:05.683121Z] Finished clobber step (success) 18:12:05 INFO - [mozharness: 2017-07-18 01:12:05.683221Z] Running read-buildbot-config step. 18:12:05 INFO - Running pre-action listener: _resource_record_pre_action 18:12:05 INFO - Running main action method: read_buildbot_config 18:12:05 INFO - Using buildbot properties: 18:12:05 INFO - { 18:12:05 INFO - "project": "", 18:12:05 INFO - "product": "firefox", 18:12:05 INFO - "who": "kwierso@gmail.com", 18:12:05 INFO - "installer_path": "public/build/target.tar.bz2", 18:12:05 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 18:12:05 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 18:12:05 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 18:12:05 INFO - "stage_platform": "linux64", 18:12:05 INFO - "basedir": "/builds/slave/test", 18:12:05 INFO - "buildnumber": 725, 18:12:05 INFO - "platform": "ubuntu64_hw", 18:12:05 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 18:12:05 INFO - "slavebuilddir": "test", 18:12:05 INFO - "taskId": "J0SnyXxURNGAp9zvGfWdfQ", 18:12:05 INFO - "branch": "mozilla-central", 18:12:05 INFO - "script_repo_revision": "production", 18:12:05 INFO - "revision": "5e73b9798464c3f7106f0161dc9a49b234f42f9c", 18:12:05 INFO - "slavename": "talos-linux64-ix-047", 18:12:05 INFO - "repo_path": "mozilla-central" 18:12:05 INFO - } 18:12:05 INFO - Finding installer, test and symbols from parent task. 18:12:05 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J0SnyXxURNGAp9zvGfWdfQ'}, attempt #1 18:12:06 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J0SnyXxURNGAp9zvGfWdfQ'}, attempt #1 18:12:06 INFO - Task dependencies: H7H9rhlpTy6vV-5GODkT0Q 18:12:06 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/H7H9rhlpTy6vV-5GODkT0Q'}, attempt #1 18:12:07 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/H7H9rhlpTy6vV-5GODkT0Q/artifacts/public/build/target.tar.bz2 18:12:07 INFO - Set test_url: https://queue.taskcluster.net/v1/task/H7H9rhlpTy6vV-5GODkT0Q/artifacts/public/build/test_packages.json 18:12:07 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/H7H9rhlpTy6vV-5GODkT0Q/artifacts/public/build/target.crashreporter-symbols.zip 18:12:07 INFO - Running post-action listener: _resource_record_post_action 18:12:07 INFO - [mozharness: 2017-07-18 01:12:07.039199Z] Finished read-buildbot-config step (success) 18:12:07 INFO - [mozharness: 2017-07-18 01:12:07.039422Z] Running download-and-extract step. 18:12:07 INFO - Running pre-action listener: _resource_record_pre_action 18:12:07 INFO - Running main action method: download_and_extract 18:12:07 INFO - mkdir: /builds/slave/test/build/tests 18:12:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:07 INFO - trying https://queue.taskcluster.net/v1/task/H7H9rhlpTy6vV-5GODkT0Q/artifacts/public/build/target.test_packages.json 18:12:07 INFO - Downloading https://queue.taskcluster.net/v1/task/H7H9rhlpTy6vV-5GODkT0Q/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 18:12:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/H7H9rhlpTy6vV-5GODkT0Q/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 18:12:07 INFO - Downloaded 989 bytes. 18:12:07 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 18:12:07 INFO - Using the following test package requirements: 18:12:07 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 18:12:07 INFO - u'common': [u'target.common.tests.zip'], 18:12:07 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 18:12:07 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 18:12:07 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 18:12:07 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 18:12:07 INFO - u'mozbase': [u'target.common.tests.zip'], 18:12:07 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 18:12:07 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 18:12:07 INFO - u'web-platform': [u'target.common.tests.zip', 18:12:07 INFO - u'target.web-platform.tests.tar.gz'], 18:12:07 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 18:12:07 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 18:12:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/H7H9rhlpTy6vV-5GODkT0Q/artifacts/public/build/target.common.tests.zip 18:12:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/H7H9rhlpTy6vV-5GODkT0Q/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:12:07 INFO - Fetch https://queue.taskcluster.net/v1/task/H7H9rhlpTy6vV-5GODkT0Q/artifacts/public/build/target.common.tests.zip into memory 18:12:09 INFO - Content-Length response header: 72769414 18:12:09 INFO - Bytes received: 72769414 18:12:20 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 18:12:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/H7H9rhlpTy6vV-5GODkT0Q/artifacts/public/build/target.common.tests.zip 18:12:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/H7H9rhlpTy6vV-5GODkT0Q/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:12:20 INFO - Fetch https://queue.taskcluster.net/v1/task/H7H9rhlpTy6vV-5GODkT0Q/artifacts/public/build/target.common.tests.zip into memory 18:12:22 INFO - Content-Length response header: 72769414 18:12:22 INFO - Bytes received: 72769414 18:12:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/H7H9rhlpTy6vV-5GODkT0Q/artifacts/public/build/target.talos.tests.zip 18:12:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/H7H9rhlpTy6vV-5GODkT0Q/artifacts/public/build/target.talos.tests.zip'}, attempt #1 18:12:34 INFO - Fetch https://queue.taskcluster.net/v1/task/H7H9rhlpTy6vV-5GODkT0Q/artifacts/public/build/target.talos.tests.zip into memory 18:12:35 INFO - Content-Length response header: 14495033 18:12:35 INFO - Bytes received: 14495033 18:12:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:35 INFO - trying https://queue.taskcluster.net/v1/task/H7H9rhlpTy6vV-5GODkT0Q/artifacts/public/build/target.tar.bz2 18:12:35 INFO - Downloading https://queue.taskcluster.net/v1/task/H7H9rhlpTy6vV-5GODkT0Q/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 18:12:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/H7H9rhlpTy6vV-5GODkT0Q/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 18:12:38 INFO - Downloaded 61880010 bytes. 18:12:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/H7H9rhlpTy6vV-5GODkT0Q/artifacts/public/build/target.tar.bz2 18:12:38 INFO - mkdir: /builds/slave/test/properties 18:12:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:12:38 INFO - Writing to file /builds/slave/test/properties/build_url 18:12:38 INFO - Contents: 18:12:38 INFO - build_url:https://queue.taskcluster.net/v1/task/H7H9rhlpTy6vV-5GODkT0Q/artifacts/public/build/target.tar.bz2 18:12:38 INFO - Running post-action listener: _resource_record_post_action 18:12:38 INFO - Running post-action listener: set_extra_try_arguments 18:12:38 INFO - [mozharness: 2017-07-18 01:12:38.576935Z] Finished download-and-extract step (success) 18:12:38 INFO - [mozharness: 2017-07-18 01:12:38.577164Z] Running populate-webroot step. 18:12:38 INFO - Running pre-action listener: _resource_record_pre_action 18:12:38 INFO - Running main action method: populate_webroot 18:12:38 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:12:38 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:12:38 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:12:38 INFO - '--tppagecycles', 18:12:38 INFO - '7'], 18:12:38 INFO - 'tests': ['tsvgm']}}, 18:12:38 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:12:38 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:12:38 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:12:38 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:12:38 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:12:38 INFO - 'talos_options': ['--disable-e10s'], 18:12:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:12:38 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:12:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:12:38 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:12:38 INFO - 'talos_options': ['--disable-e10s'], 18:12:38 INFO - 'tests': ['damp', 'tps']}, 18:12:38 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:12:38 INFO - 'tests': ['damp', 'tps']}, 18:12:38 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:12:38 INFO - 'tests': ['dromaeo_dom']}, 18:12:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:12:38 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:12:38 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 18:12:38 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:12:38 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:12:38 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:12:38 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:12:38 INFO - 'tests': ['a11yr', 18:12:38 INFO - 'ts_paint', 18:12:38 INFO - 'tpaint', 18:12:38 INFO - 'sessionrestore', 18:12:38 INFO - 'sessionrestore_no_auto_restore', 18:12:38 INFO - 'tabpaint']}, 18:12:38 INFO - 'other-e10s': {'tests': ['a11yr', 18:12:38 INFO - 'ts_paint', 18:12:38 INFO - 'tpaint', 18:12:38 INFO - 'sessionrestore', 18:12:38 INFO - 'sessionrestore_many_windows', 18:12:38 INFO - 'sessionrestore_no_auto_restore', 18:12:38 INFO - 'tabpaint']}, 18:12:38 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:12:38 INFO - 'ts_paint', 18:12:38 INFO - 'tpaint', 18:12:38 INFO - 'sessionrestore', 18:12:38 INFO - 'sessionrestore_many_windows', 18:12:38 INFO - 'sessionrestore_no_auto_restore', 18:12:38 INFO - 'tabpaint']}, 18:12:38 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:12:38 INFO - 'ts_paint', 18:12:38 INFO - 'tpaint', 18:12:38 INFO - 'sessionrestore', 18:12:38 INFO - 'sessionrestore_many_windows', 18:12:38 INFO - 'sessionrestore_no_auto_restore', 18:12:38 INFO - 'tabpaint']}, 18:12:38 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:12:38 INFO - 'tests': ['a11yr', 18:12:38 INFO - 'ts_paint', 18:12:38 INFO - 'tpaint', 18:12:38 INFO - 'sessionrestore', 18:12:38 INFO - 'sessionrestore_no_auto_restore', 18:12:38 INFO - 'tabpaint']}, 18:12:38 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:12:38 INFO - 'tests': ['a11yr', 18:12:38 INFO - 'ts_paint', 18:12:38 INFO - 'tpaint', 18:12:38 INFO - 'sessionrestore', 18:12:38 INFO - 'sessionrestore_no_auto_restore', 18:12:38 INFO - 'tabpaint']}, 18:12:38 INFO - 'perf-reftest': {'tests': ['bloom_basic']}, 18:12:38 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 18:12:38 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 18:12:38 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:12:38 INFO - 'talos_options': ['--mitmproxy', 18:12:38 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:12:38 INFO - '--firstNonBlankPaint'], 18:12:38 INFO - 'tests': ['quantum_pageload_google', 18:12:38 INFO - 'quantum_pageload_youtube', 18:12:38 INFO - 'quantum_pageload_amazon', 18:12:38 INFO - 'quantum_pageload_facebook']}, 18:12:38 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:12:38 INFO - 'tests': ['tsvgx', 18:12:38 INFO - 'tsvgr_opacity', 18:12:38 INFO - 'tart', 18:12:38 INFO - 'tscrollx', 18:12:38 INFO - 'cart', 18:12:38 INFO - 'tsvg_static']}, 18:12:38 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:12:38 INFO - 'tsvgr_opacity', 18:12:38 INFO - 'tart', 18:12:38 INFO - 'tscrollx', 18:12:38 INFO - 'cart', 18:12:38 INFO - 'tsvg_static']}, 18:12:38 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:12:38 INFO - 'talos_options': ['--disable-e10s'], 18:12:38 INFO - 'tests': ['tp5o']}, 18:12:38 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:12:38 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:12:38 INFO - 'talos_options': ['--disable-e10s', 18:12:38 INFO - '--xperf_path', 18:12:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:12:38 INFO - 'tests': ['tp5n']}, 18:12:38 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:12:38 INFO - 'talos_options': ['--xperf_path', 18:12:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:12:38 INFO - 'tests': ['tp5n']}}, 18:12:38 INFO - 'talos.zip': {'path': '', 18:12:38 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:12:38 INFO - Running post-action listener: _resource_record_post_action 18:12:38 INFO - [mozharness: 2017-07-18 01:12:38.597522Z] Finished populate-webroot step (success) 18:12:38 INFO - [mozharness: 2017-07-18 01:12:38.597613Z] Running create-virtualenv step. 18:12:38 INFO - Running pre-action listener: _resource_record_pre_action 18:12:38 INFO - Running main action method: create_virtualenv 18:12:38 INFO - Creating virtualenv /builds/slave/test/build/venv 18:12:38 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:12:38 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:12:38 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 18:12:38 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:12:38 INFO - Using real prefix '/usr' 18:12:38 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:12:39 INFO - Installing distribute.............................................................................................................................................................................................done. 18:12:41 INFO - Installing pip.................done. 18:12:41 INFO - Return code: 0 18:12:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:12:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:12:41 INFO - Reading from file tmpfile_stdout 18:12:41 INFO - Output received: 18:12:41 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 18:12:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:12:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11851f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1353a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14f6b90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14f3d10>, '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 0x14f4c10>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14f50c0>, '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': '03627c79f6bb3d1578c4a402000001ba-1500340271.561761-941163189', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:12:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:12:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:12:41 INFO - Using env: {'DISPLAY': ':0', 18:12:41 INFO - 'HOME': '/home/cltbld', 18:12:41 INFO - 'LANG': 'en_US.UTF-8', 18:12:41 INFO - 'LANGUAGE': 'en_US:en', 18:12:41 INFO - 'LOGNAME': 'cltbld', 18:12:41 INFO - 'MAIL': '/var/mail/cltbld', 18:12:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:41 INFO - 'MOZ_NO_REMOTE': '1', 18:12:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:41 INFO - 'NO_EM_RESTART': '1', 18:12:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:41 INFO - 'PWD': '/builds/slave/test', 18:12:41 INFO - 'SHELL': '/bin/bash', 18:12:41 INFO - 'SHLVL': '1', 18:12:41 INFO - 'TERM': 'linux', 18:12:41 INFO - 'TMOUT': '86400', 18:12:41 INFO - 'USER': 'cltbld', 18:12:41 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1500340271.561761-941163189', 18:12:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:41 INFO - '_': '/tools/buildbot/bin/python'} 18:12:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:41 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 18:12:41 INFO - Cleaning up... 18:12:41 INFO - Return code: 0 18:12:41 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:12:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11851f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1353a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14f6b90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14f3d10>, '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 0x14f4c10>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14f50c0>, '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': '03627c79f6bb3d1578c4a402000001ba-1500340271.561761-941163189', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:12:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:12:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:12:41 INFO - Using env: {'DISPLAY': ':0', 18:12:41 INFO - 'HOME': '/home/cltbld', 18:12:41 INFO - 'LANG': 'en_US.UTF-8', 18:12:41 INFO - 'LANGUAGE': 'en_US:en', 18:12:41 INFO - 'LOGNAME': 'cltbld', 18:12:41 INFO - 'MAIL': '/var/mail/cltbld', 18:12:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:41 INFO - 'MOZ_NO_REMOTE': '1', 18:12:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:41 INFO - 'NO_EM_RESTART': '1', 18:12:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:41 INFO - 'PWD': '/builds/slave/test', 18:12:41 INFO - 'SHELL': '/bin/bash', 18:12:41 INFO - 'SHLVL': '1', 18:12:41 INFO - 'TERM': 'linux', 18:12:41 INFO - 'TMOUT': '86400', 18:12:41 INFO - 'USER': 'cltbld', 18:12:41 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1500340271.561761-941163189', 18:12:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:41 INFO - '_': '/tools/buildbot/bin/python'} 18:12:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:41 INFO - Downloading/unpacking psutil>=3.1.1 18:12:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:12:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:12:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:12:43 INFO - Installing collected packages: psutil 18:12:43 INFO - Running setup.py install for psutil 18:12:43 INFO - building 'psutil._psutil_linux' extension 18:12:43 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 18:12:43 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 18:12:43 INFO - building 'psutil._psutil_posix' extension 18:12:43 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 18:12:43 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 18:12:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:12:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:12:43 INFO - Successfully installed psutil 18:12:43 INFO - Cleaning up... 18:12:43 INFO - Return code: 0 18:12:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:12:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11851f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1353a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14f6b90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14f3d10>, '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 0x14f4c10>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14f50c0>, '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': '03627c79f6bb3d1578c4a402000001ba-1500340271.561761-941163189', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:12:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:12:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:12:43 INFO - Using env: {'DISPLAY': ':0', 18:12:43 INFO - 'HOME': '/home/cltbld', 18:12:43 INFO - 'LANG': 'en_US.UTF-8', 18:12:43 INFO - 'LANGUAGE': 'en_US:en', 18:12:43 INFO - 'LOGNAME': 'cltbld', 18:12:43 INFO - 'MAIL': '/var/mail/cltbld', 18:12:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:43 INFO - 'MOZ_NO_REMOTE': '1', 18:12:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:43 INFO - 'NO_EM_RESTART': '1', 18:12:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:43 INFO - 'PWD': '/builds/slave/test', 18:12:43 INFO - 'SHELL': '/bin/bash', 18:12:43 INFO - 'SHLVL': '1', 18:12:43 INFO - 'TERM': 'linux', 18:12:43 INFO - 'TMOUT': '86400', 18:12:43 INFO - 'USER': 'cltbld', 18:12:43 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1500340271.561761-941163189', 18:12:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:43 INFO - '_': '/tools/buildbot/bin/python'} 18:12:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:12:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:12:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:12:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:12:44 INFO - Installing collected packages: mozsystemmonitor 18:12:44 INFO - Running setup.py install for mozsystemmonitor 18:12:45 INFO - Successfully installed mozsystemmonitor 18:12:45 INFO - Cleaning up... 18:12:45 INFO - Return code: 0 18:12:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:12:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11851f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1353a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14f6b90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14f3d10>, '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 0x14f4c10>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14f50c0>, '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': '03627c79f6bb3d1578c4a402000001ba-1500340271.561761-941163189', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:12:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:12:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:12:45 INFO - Using env: {'DISPLAY': ':0', 18:12:45 INFO - 'HOME': '/home/cltbld', 18:12:45 INFO - 'LANG': 'en_US.UTF-8', 18:12:45 INFO - 'LANGUAGE': 'en_US:en', 18:12:45 INFO - 'LOGNAME': 'cltbld', 18:12:45 INFO - 'MAIL': '/var/mail/cltbld', 18:12:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:45 INFO - 'MOZ_NO_REMOTE': '1', 18:12:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:45 INFO - 'NO_EM_RESTART': '1', 18:12:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:45 INFO - 'PWD': '/builds/slave/test', 18:12:45 INFO - 'SHELL': '/bin/bash', 18:12:45 INFO - 'SHLVL': '1', 18:12:45 INFO - 'TERM': 'linux', 18:12:45 INFO - 'TMOUT': '86400', 18:12:45 INFO - 'USER': 'cltbld', 18:12:45 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1500340271.561761-941163189', 18:12:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:45 INFO - '_': '/tools/buildbot/bin/python'} 18:12:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:45 INFO - Downloading/unpacking jsonschema==2.5.1 18:12:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:12:46 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:12:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:46 INFO - Downloading functools32-3.2.3-2.tar.gz 18:12:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:12:46 INFO - warning: no files found matching '*.txt' 18:12:46 INFO - no previously-included directories found matching 'build' 18:12:46 INFO - no previously-included directories found matching 'dist' 18:12:46 INFO - no previously-included directories found matching '.git*' 18:12:46 INFO - Installing collected packages: jsonschema, functools32 18:12:46 INFO - Running setup.py install for functools32 18:12:46 INFO - warning: no files found matching '*.txt' 18:12:46 INFO - no previously-included directories found matching 'build' 18:12:46 INFO - no previously-included directories found matching 'dist' 18:12:46 INFO - no previously-included directories found matching '.git*' 18:12:46 INFO - Successfully installed jsonschema functools32 18:12:46 INFO - Cleaning up... 18:12:46 INFO - Return code: 0 18:12:46 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:12:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11851f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1353a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14f6b90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14f3d10>, '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 0x14f4c10>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14f50c0>, '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': '03627c79f6bb3d1578c4a402000001ba-1500340271.561761-941163189', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:12:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:12:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:12:46 INFO - Using env: {'DISPLAY': ':0', 18:12:46 INFO - 'HOME': '/home/cltbld', 18:12:46 INFO - 'LANG': 'en_US.UTF-8', 18:12:46 INFO - 'LANGUAGE': 'en_US:en', 18:12:46 INFO - 'LOGNAME': 'cltbld', 18:12:46 INFO - 'MAIL': '/var/mail/cltbld', 18:12:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:46 INFO - 'MOZ_NO_REMOTE': '1', 18:12:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:46 INFO - 'NO_EM_RESTART': '1', 18:12:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:46 INFO - 'PWD': '/builds/slave/test', 18:12:46 INFO - 'SHELL': '/bin/bash', 18:12:46 INFO - 'SHLVL': '1', 18:12:46 INFO - 'TERM': 'linux', 18:12:46 INFO - 'TMOUT': '86400', 18:12:46 INFO - 'USER': 'cltbld', 18:12:46 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1500340271.561761-941163189', 18:12:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:46 INFO - '_': '/tools/buildbot/bin/python'} 18:12:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:46 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:12:46 INFO - Cleaning up... 18:12:46 INFO - Return code: 0 18:12:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:12:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11851f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1353a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14f6b90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14f3d10>, '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 0x14f4c10>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14f50c0>, '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': '03627c79f6bb3d1578c4a402000001ba-1500340271.561761-941163189', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:12:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:12:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:12:46 INFO - Using env: {'DISPLAY': ':0', 18:12:46 INFO - 'HOME': '/home/cltbld', 18:12:46 INFO - 'LANG': 'en_US.UTF-8', 18:12:46 INFO - 'LANGUAGE': 'en_US:en', 18:12:46 INFO - 'LOGNAME': 'cltbld', 18:12:46 INFO - 'MAIL': '/var/mail/cltbld', 18:12:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:46 INFO - 'MOZ_NO_REMOTE': '1', 18:12:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:46 INFO - 'NO_EM_RESTART': '1', 18:12:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:46 INFO - 'PWD': '/builds/slave/test', 18:12:46 INFO - 'SHELL': '/bin/bash', 18:12:46 INFO - 'SHLVL': '1', 18:12:46 INFO - 'TERM': 'linux', 18:12:46 INFO - 'TMOUT': '86400', 18:12:46 INFO - 'USER': 'cltbld', 18:12:46 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1500340271.561761-941163189', 18:12:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:46 INFO - '_': '/tools/buildbot/bin/python'} 18:12:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:47 INFO - Downloading/unpacking blobuploader==1.2.4 18:12:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:48 INFO - Downloading blobuploader-1.2.4.tar.gz 18:12:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:12:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:12:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:12:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:12:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:48 INFO - Downloading docopt-0.6.1.tar.gz 18:12:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:12:48 INFO - Installing collected packages: blobuploader, requests, docopt 18:12:48 INFO - Running setup.py install for blobuploader 18:12:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:12:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:12:48 INFO - Running setup.py install for requests 18:12:49 INFO - Running setup.py install for docopt 18:12:49 INFO - Successfully installed blobuploader requests docopt 18:12:49 INFO - Cleaning up... 18:12:49 INFO - Return code: 0 18:12:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:12:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11851f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1353a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14f6b90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14f3d10>, '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 0x14f4c10>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14f50c0>, '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': '03627c79f6bb3d1578c4a402000001ba-1500340271.561761-941163189', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:12:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:12:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:12:49 INFO - Using env: {'DISPLAY': ':0', 18:12:49 INFO - 'HOME': '/home/cltbld', 18:12:49 INFO - 'LANG': 'en_US.UTF-8', 18:12:49 INFO - 'LANGUAGE': 'en_US:en', 18:12:49 INFO - 'LOGNAME': 'cltbld', 18:12:49 INFO - 'MAIL': '/var/mail/cltbld', 18:12:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:49 INFO - 'MOZ_NO_REMOTE': '1', 18:12:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:49 INFO - 'NO_EM_RESTART': '1', 18:12:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:49 INFO - 'PWD': '/builds/slave/test', 18:12:49 INFO - 'SHELL': '/bin/bash', 18:12:49 INFO - 'SHLVL': '1', 18:12:49 INFO - 'TERM': 'linux', 18:12:49 INFO - 'TMOUT': '86400', 18:12:49 INFO - 'USER': 'cltbld', 18:12:49 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1500340271.561761-941163189', 18:12:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:49 INFO - '_': '/tools/buildbot/bin/python'} 18:12:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:12:49 INFO - Running setup.py (path:/tmp/pip-nuOqal-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:12:49 INFO - Running setup.py (path:/tmp/pip-xTUOIm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:12:49 INFO - Running setup.py (path:/tmp/pip-gYV3Fl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:12:49 INFO - Running setup.py (path:/tmp/pip-V9jA0a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:12:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:12:49 INFO - Running setup.py (path:/tmp/pip-xKfAS8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:12:50 INFO - Running setup.py (path:/tmp/pip-8ND2Di-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:12:50 INFO - Running setup.py (path:/tmp/pip-irHs1N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:12:50 INFO - Running setup.py (path:/tmp/pip-abtPCp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:12:50 INFO - Running setup.py (path:/tmp/pip-nf3nTe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:12:50 INFO - Running setup.py (path:/tmp/pip-CTjosP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:12:50 INFO - Running setup.py (path:/tmp/pip-12aZow-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:12:50 INFO - Running setup.py (path:/tmp/pip-zRTd6G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:12:50 INFO - Running setup.py (path:/tmp/pip-206W81-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:12:50 INFO - Running setup.py (path:/tmp/pip-3pzHlZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:12:50 INFO - Running setup.py (path:/tmp/pip-6rjW0p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:12:50 INFO - Running setup.py (path:/tmp/pip-OLmmft-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:12:50 INFO - Running setup.py (path:/tmp/pip-ANuqCV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:12:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:12:50 INFO - Running setup.py install for manifestparser 18:12:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:12:51 INFO - Running setup.py install for mozcrash 18:12:51 INFO - Running setup.py install for mozdebug 18:12:51 INFO - Running setup.py install for mozdevice 18:12:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:12:51 INFO - Running setup.py install for mozfile 18:12:51 INFO - Running setup.py install for mozhttpd 18:12:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:12:51 INFO - Running setup.py install for mozinfo 18:12:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:12:51 INFO - Running setup.py install for mozInstall 18:12:51 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:12:51 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:12:51 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:12:51 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:12:51 INFO - Running setup.py install for mozleak 18:12:51 INFO - Running setup.py install for mozlog 18:12:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:12:51 INFO - Running setup.py install for moznetwork 18:12:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:12:52 INFO - Running setup.py install for mozprocess 18:12:52 INFO - Running setup.py install for mozprofile 18:12:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:12:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:12:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:12:52 INFO - Running setup.py install for mozrunner 18:12:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:12:52 INFO - Running setup.py install for mozscreenshot 18:12:52 INFO - Running setup.py install for moztest 18:12:52 INFO - Running setup.py install for mozversion 18:12:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:12:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:12:52 INFO - Cleaning up... 18:12:52 INFO - Return code: 0 18:12:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:12:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11851f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1353a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14f6b90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14f3d10>, '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 0x14f4c10>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14f50c0>, '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': '03627c79f6bb3d1578c4a402000001ba-1500340271.561761-941163189', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:12:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:12:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:12:52 INFO - Using env: {'DISPLAY': ':0', 18:12:52 INFO - 'HOME': '/home/cltbld', 18:12:52 INFO - 'LANG': 'en_US.UTF-8', 18:12:52 INFO - 'LANGUAGE': 'en_US:en', 18:12:52 INFO - 'LOGNAME': 'cltbld', 18:12:52 INFO - 'MAIL': '/var/mail/cltbld', 18:12:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:52 INFO - 'MOZ_NO_REMOTE': '1', 18:12:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:52 INFO - 'NO_EM_RESTART': '1', 18:12:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:52 INFO - 'PWD': '/builds/slave/test', 18:12:52 INFO - 'SHELL': '/bin/bash', 18:12:52 INFO - 'SHLVL': '1', 18:12:52 INFO - 'TERM': 'linux', 18:12:52 INFO - 'TMOUT': '86400', 18:12:52 INFO - 'USER': 'cltbld', 18:12:52 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1500340271.561761-941163189', 18:12:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:52 INFO - '_': '/tools/buildbot/bin/python'} 18:12:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:12:52 INFO - Running setup.py (path:/tmp/pip-jrDx1N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:12:52 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 18:12:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:12:52 INFO - Running setup.py (path:/tmp/pip-StdbTK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:12:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:12:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:12:53 INFO - Running setup.py (path:/tmp/pip-qdICl2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:12:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:12:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:12:53 INFO - Running setup.py (path:/tmp/pip-mD6Tts-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:12:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:12:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:12:53 INFO - Running setup.py (path:/tmp/pip-sxFW_5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:12:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 18:12:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:12:53 INFO - Running setup.py (path:/tmp/pip-1NUTRl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:12:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 18:12:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:12:53 INFO - Running setup.py (path:/tmp/pip-kUKb6A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:12:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 18:12:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:12:53 INFO - Running setup.py (path:/tmp/pip-MOGjDy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:12:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:12:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:12:53 INFO - Running setup.py (path:/tmp/pip-TeDpG7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:12:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 18:12:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:12:53 INFO - Running setup.py (path:/tmp/pip-qq3w9p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:12:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:12:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:12:53 INFO - Running setup.py (path:/tmp/pip-PNra8f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:12:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 18:12:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:12:53 INFO - Running setup.py (path:/tmp/pip-iNDOIe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:12:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 18:12:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:12:53 INFO - Running setup.py (path:/tmp/pip-welk4m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:12:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 18:12:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:12:53 INFO - Running setup.py (path:/tmp/pip-ytd3uK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:12:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 18:12:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:12:53 INFO - Running setup.py (path:/tmp/pip-pu28mS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:12:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 18:12:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:12:53 INFO - Running setup.py (path:/tmp/pip-ExhIbf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:12:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 18:12:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:12:54 INFO - Running setup.py (path:/tmp/pip-0Q9Z09-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:12:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 18:12:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:12:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:12:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:12:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:12:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:12:54 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:12:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:12:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:55 INFO - Downloading blessings-1.6.tar.gz 18:12:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:12:55 INFO - Installing collected packages: blessings 18:12:55 INFO - Running setup.py install for blessings 18:12:55 INFO - Successfully installed blessings 18:12:55 INFO - Cleaning up... 18:12:55 INFO - Return code: 0 18:12:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:12:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:12:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:12:55 INFO - Reading from file tmpfile_stdout 18:12:55 INFO - Current package versions: 18:12:55 INFO - argparse == 1.2.1 18:12:55 INFO - blessings == 1.6 18:12:55 INFO - blobuploader == 1.2.4 18:12:55 INFO - docopt == 0.6.1 18:12:55 INFO - functools32 == 3.2.3-2 18:12:55 INFO - jsonschema == 2.5.1 18:12:55 INFO - manifestparser == 1.1 18:12:55 INFO - mozInstall == 1.13 18:12:55 INFO - mozcrash == 1.0 18:12:55 INFO - mozdebug == 0.1 18:12:55 INFO - mozdevice == 0.51 18:12:55 INFO - mozfile == 1.2 18:12:55 INFO - mozhttpd == 0.7 18:12:55 INFO - mozinfo == 0.10 18:12:55 INFO - mozleak == 0.1 18:12:55 INFO - mozlog == 3.5 18:12:55 INFO - moznetwork == 0.27 18:12:55 INFO - mozprocess == 0.25 18:12:55 INFO - mozprofile == 0.28 18:12:55 INFO - mozrunner == 6.13 18:12:55 INFO - mozscreenshot == 0.1 18:12:55 INFO - mozsystemmonitor == 0.3 18:12:55 INFO - moztest == 0.8 18:12:55 INFO - mozversion == 1.4 18:12:55 INFO - psutil == 3.1.1 18:12:55 INFO - requests == 1.2.3 18:12:55 INFO - wsgiref == 0.1.2 18:12:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:12:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11851f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1353a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14f6b90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14f3d10>, '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 0x14f4c10>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14f50c0>, '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': '03627c79f6bb3d1578c4a402000001ba-1500340271.561761-941163189', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:12:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 18:12:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:12:55 INFO - Using env: {'DISPLAY': ':0', 18:12:55 INFO - 'HOME': '/home/cltbld', 18:12:55 INFO - 'LANG': 'en_US.UTF-8', 18:12:55 INFO - 'LANGUAGE': 'en_US:en', 18:12:55 INFO - 'LOGNAME': 'cltbld', 18:12:55 INFO - 'MAIL': '/var/mail/cltbld', 18:12:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:55 INFO - 'MOZ_NO_REMOTE': '1', 18:12:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:55 INFO - 'NO_EM_RESTART': '1', 18:12:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:55 INFO - 'PWD': '/builds/slave/test', 18:12:55 INFO - 'SHELL': '/bin/bash', 18:12:55 INFO - 'SHLVL': '1', 18:12:55 INFO - 'TERM': 'linux', 18:12:55 INFO - 'TMOUT': '86400', 18:12:55 INFO - 'USER': 'cltbld', 18:12:55 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1500340271.561761-941163189', 18:12:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:55 INFO - '_': '/tools/buildbot/bin/python'} 18:12:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:12:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 18:12:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 18:12:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:12:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 18:12:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 18:12:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 18:12:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 18:12:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 18:12:55 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:12:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:56 INFO - Downloading marionette_driver-1.4.0.tar.gz 18:12:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 18:12:57 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11)) 18:12:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:12:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:12:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 18:12:57 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:12:57 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:12:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:12:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:12:57 INFO - Installing collected packages: marionette-driver, simplejson 18:12:57 INFO - Running setup.py install for marionette-driver 18:12:57 INFO - Running setup.py install for simplejson 18:12:57 INFO - building 'simplejson._speedups' extension 18:12:57 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 18:12:58 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 18:12:58 INFO - Successfully installed marionette-driver simplejson 18:12:58 INFO - Cleaning up... 18:12:58 INFO - Return code: 0 18:12:58 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:12:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11851f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1353a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14f6b90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14f3d10>, '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 0x14f4c10>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14f50c0>, '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': '03627c79f6bb3d1578c4a402000001ba-1500340271.561761-941163189', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:12:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:12:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:12:58 INFO - Using env: {'DISPLAY': ':0', 18:12:58 INFO - 'HOME': '/home/cltbld', 18:12:58 INFO - 'LANG': 'en_US.UTF-8', 18:12:58 INFO - 'LANGUAGE': 'en_US:en', 18:12:58 INFO - 'LOGNAME': 'cltbld', 18:12:58 INFO - 'MAIL': '/var/mail/cltbld', 18:12:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:58 INFO - 'MOZ_NO_REMOTE': '1', 18:12:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:58 INFO - 'NO_EM_RESTART': '1', 18:12:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:58 INFO - 'PWD': '/builds/slave/test', 18:12:58 INFO - 'SHELL': '/bin/bash', 18:12:58 INFO - 'SHLVL': '1', 18:12:58 INFO - 'TERM': 'linux', 18:12:58 INFO - 'TMOUT': '86400', 18:12:58 INFO - 'USER': 'cltbld', 18:12:58 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1500340271.561761-941163189', 18:12:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:58 INFO - '_': '/tools/buildbot/bin/python'} 18:12:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:58 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:12:58 INFO - Cleaning up... 18:12:58 INFO - Return code: 0 18:12:58 INFO - Running post-action listener: _resource_record_post_action 18:12:58 INFO - Running post-action listener: _start_resource_monitoring 18:12:58 INFO - Starting resource monitoring. 18:12:58 INFO - [mozharness: 2017-07-18 01:12:58.699776Z] Finished create-virtualenv step (success) 18:12:58 INFO - [mozharness: 2017-07-18 01:12:58.700249Z] Running install step. 18:12:58 INFO - Running pre-action listener: _resource_record_pre_action 18:12:58 INFO - Running main action method: install 18:12:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:12:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:12:58 INFO - Reading from file tmpfile_stdout 18:12:58 INFO - Detecting whether we're running mozinstall >=1.0... 18:12:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:12:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:12:59 INFO - Reading from file tmpfile_stdout 18:12:59 INFO - Output received: 18:12:59 INFO - Usage: mozinstall [options] installer 18:12:59 INFO - Options: 18:12:59 INFO - -h, --help show this help message and exit 18:12:59 INFO - -d DEST, --destination=DEST 18:12:59 INFO - Directory to install application into. [default: 18:12:59 INFO - "/builds/slave/test"] 18:12:59 INFO - --app=APP Application being installed. [default: firefox] 18:12:59 INFO - mkdir: /builds/slave/test/build/application 18:12:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 18:12:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 18:13:16 INFO - Reading from file tmpfile_stdout 18:13:16 INFO - Output received: 18:13:16 INFO - /builds/slave/test/build/application/firefox/firefox 18:13:16 INFO - Running post-action listener: _resource_record_post_action 18:13:16 INFO - [mozharness: 2017-07-18 01:13:16.963124Z] Finished install step (success) 18:13:16 INFO - [mozharness: 2017-07-18 01:13:16.963375Z] Running setup-mitmproxy step. 18:13:16 INFO - Running pre-action listener: _resource_record_pre_action 18:13:16 INFO - Running main action method: setup_mitmproxy 18:13:16 INFO - Skipping: mitmproxy is not required 18:13:16 INFO - Running post-action listener: _resource_record_post_action 18:13:16 INFO - [mozharness: 2017-07-18 01:13:16.964511Z] Finished setup-mitmproxy step (success) 18:13:16 INFO - [mozharness: 2017-07-18 01:13:16.964733Z] Running run-tests step. 18:13:16 INFO - Running pre-action listener: _resource_record_pre_action 18:13:16 INFO - Running pre-action listener: _set_gcov_prefix 18:13:16 INFO - Running main action method: run_tests 18:13:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:13:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:13:16 INFO - Python 2.7.3 18:13:16 INFO - Return code: 0 18:13:16 INFO - grabbing minidump binary from tooltool 18:13:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:13:16 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 0x14f3d10>, '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 0x14f4c10>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14f50c0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:13:16 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 18:13:16 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 18:13:16 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 18:13:17 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:13:17 INFO - Return code: 0 18:13:17 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:13:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:13:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:13:17 INFO - ENV: RUST_BACKTRACE is now 1 18:13:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:13:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:13:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/H7H9rhlpTy6vV-5GODkT0Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 18:13:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/H7H9rhlpTy6vV-5GODkT0Q/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-047 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 18:13:17 INFO - Using env: {'DISPLAY': ':0', 18:13:17 INFO - 'HOME': '/home/cltbld', 18:13:17 INFO - 'LANG': 'en_US.UTF-8', 18:13:17 INFO - 'LANGUAGE': 'en_US:en', 18:13:17 INFO - 'LOGNAME': 'cltbld', 18:13:17 INFO - 'MAIL': '/var/mail/cltbld', 18:13:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:13:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:13:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:13:17 INFO - 'MOZ_NO_REMOTE': '1', 18:13:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:13:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:13:17 INFO - 'NO_EM_RESTART': '1', 18:13:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:13:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:13:17 INFO - 'PWD': '/builds/slave/test', 18:13:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:13:17 INFO - 'RUST_BACKTRACE': '1', 18:13:17 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:13:17 INFO - 'SHELL': '/bin/bash', 18:13:17 INFO - 'SHLVL': '1', 18:13:17 INFO - 'TERM': 'linux', 18:13:17 INFO - 'TMOUT': '86400', 18:13:17 INFO - 'USER': 'cltbld', 18:13:17 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1500340271.561761-941163189', 18:13:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:13:17 INFO - '_': '/tools/buildbot/bin/python'} 18:13:17 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/H7H9rhlpTy6vV-5GODkT0Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 18:13:17 INFO - mozversion application_buildid: 20170718002759 18:13:17 INFO - mozversion application_changeset: 5e73b9798464c3f7106f0161dc9a49b234f42f9c 18:13:17 INFO - mozversion application_display_name: Nightly 18:13:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:13:17 INFO - mozversion application_name: Firefox 18:13:17 INFO - mozversion application_remotingname: firefox 18:13:17 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:13:17 INFO - mozversion application_vendor: Mozilla 18:13:17 INFO - mozversion application_version: 56.0a1 18:13:17 INFO - mozversion platform_buildid: 20170718002759 18:13:17 INFO - mozversion platform_changeset: 5e73b9798464c3f7106f0161dc9a49b234f42f9c 18:13:17 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:13:17 INFO - mozversion platform_version: 56.0a1 18:13:17 INFO - using testdate: 1500340397 18:13:17 INFO - actual date: 1500340397 18:13:17 INFO - starting webserver on 'localhost:46988' 18:13:17 INFO - SUITE-START | Running 2 tests 18:13:17 INFO - TEST-START | dromaeo_css 18:13:17 INFO - Initialising browser for dromaeo_css test... 18:13:17 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:46988/getInfo.html -profile /tmp/tmp43SQWq/profile 18:13:17 INFO - TEST-INFO | started process 20816 (/builds/slave/test/build/application/firefox/firefox http://localhost:46988/getInfo.html) 18:13:23 INFO - TEST-INFO | 20816: exit 0 18:13:23 INFO - Browser initialized. 18:13:23 INFO - Running cycle 1/1 for dromaeo_css test... 18:13:23 INFO - TEST-INFO | started process 21025 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp43SQWq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:13:35 INFO - PID 21025 | RSS: Main: 168697856 18:13:35 INFO - PID 21025 | 18:16:33 INFO - PID 21025 | Cycle 1(1): loaded http://localhost:46988/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46988/tests/dromaeo/cssquery-ext.html) 18:16:35 INFO - PID 21025 | RSS: Main: 176107520 18:16:35 INFO - PID 21025 | 18:19:36 INFO - PID 21025 | Cycle 1(1): loaded http://localhost:46988/tests/dromaeo/cssquery-ext.html (next: http://localhost:46988/tests/dromaeo/cssquery-jquery.html) 18:19:37 INFO - PID 21025 | RSS: Main: 174317568 18:19:37 INFO - PID 21025 | 18:22:39 INFO - PID 21025 | Cycle 1(1): loaded http://localhost:46988/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46988/tests/dromaeo/cssquery-mootools.html) 18:22:39 INFO - PID 21025 | RSS: Main: 176185344 18:22:39 INFO - PID 21025 | 18:25:41 INFO - PID 21025 | Cycle 1(1): loaded http://localhost:46988/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46988/tests/dromaeo/cssquery-prototype.html) 18:25:41 INFO - PID 21025 | RSS: Main: 173813760 18:25:41 INFO - PID 21025 | 18:28:45 INFO - PID 21025 | Cycle 1(1): loaded http://localhost:46988/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46988/tests/dromaeo/cssquery-yui.html) 18:28:45 INFO - PID 21025 | RSS: Main: 174243840 18:28:45 INFO - PID 21025 | 18:31:47 INFO - PID 21025 | Cycle 1(1): loaded http://localhost:46988/tests/dromaeo/cssquery-yui.html (next: http://localhost:46988/tests/dromaeo/cssquery-dojo.html) 18:31:47 INFO - PID 21025 | RSS: Main: 178757632 18:31:47 INFO - PID 21025 | 18:31:47 INFO - PID 21025 | __start_tp_report 18:31:47 INFO - PID 21025 | _x_x_mozilla_page_load 18:31:47 INFO - PID 21025 | _x_x_mozilla_page_load_details 18:31:47 INFO - PID 21025 | |i|pagename|runs| 18:31:47 INFO - PID 21025 | |0;dojo.html;3059.281068948798;3119.7660175486835;3128.2335827722204;3154.7476201903837;3175.825329606872;5981.252343457068;6000.33996260411;6033.969830150849;6041.697915104243;6104.9694751526295;6054.485368743651;6070.453659170674;6073.180120683706;6109.908351374733;6110.2973158086725;5596.944030559704;5622.2128901953865;5639.182318563806;5644.915326270109;5664.0371136906615;5599.552035837143;5633.014222511044;5639.464250896158;5665.773369065232;5666.546676265889;5618.803341883035;5633.126865335859;5633.352164501073;5639.548836093123;5642.633228840113;5626.212330273765;5630.971845140789;5639.774409023634;5642.802501912413;5663.603547751639;5492.862678433031;5586.441355864381;5603.439656034405;5625.887482250332;5683.829485115453;5954.55340849438;6002.759889604411;6024.548158888101;6049.032154855246;6054.031354983182;6368.490520758374;6372.731745866091;6453.5805172664095;6456.2575303840185;6464.288928217892;6532.608043517404;6557.704903279364;6570.178727659043;6582.078509008743;6589.044588534635;6008.068749343815;6017.789377371771;6023.909641355383;6041.667708276043;6103.6948152592195;6576.769813056568;6690.13028306317;6742.258351581322;6756.358145976124;6826.829329266808;6326.304106548276;6406.327335629691;6429.292777794439;6481.48148148147;6520.054592084121;6520.217573891068;6545.672716364258;6614.404703576702;6624.56940298879;6626.668666566652;6349.936500635027;6408.2630497492455;6469.838254043592;6471.870562588721;6526.967365163144;6426;6437.839054023687;6521.47828173745;6537.5750576212395;6583.341665833378;5258.789648414097;5303.443138470482;5352.678839269656;5368.114261146867;5376.489233522809;4511.842085526991;4546.431696037996;4557.5898169164275;4608.746518941423;4632.930506042412;5642.689652069093;5659.320881494247;5666.886662266732;5689.516391106719;5690.6301090429815;6559.147310849559;6576.177977752781;6577.276499585042;6582.868342633216;6588.209414870246;5591.07747221712;5665.3768085510565;5670.744816483186;5672.716364181774;5702.201691763156;5710.343310519261;5729.4843464088435;5787.768489260382;5799.478046975708;5821.88356232873;6309.936900630935;6319.368063193736;6345.460635845912;6349.809505714836;6353.396427339396;6439.323870993572;6477.222733271849;6508.837279067871;6566.4418524425955;6569.408753212234;6264.404881536247;6292.622442653455;6326.9683651581445;6339.1125242467015;6408.102865598913;6331.430171284606;6340.048992651139;6344.143540622142;6441.033844923111;6445.838854028687;6472.449841763503;6497.902531462127;6559.540832141704;6561.868762624817;6592.274849766618;6282.089097080989;6285.74857005733;6355.205599300088;6377.617342959437;6393.584417012879;6386;6399.77600783989;6405.423511883766;6406.775762848278;6421.518386120966;5378.7579558920725;6270.843228919313;6314.1160237565855;6336.461400780985;6338.080978258219;6356.078368636429;6359.713812878525;6422.42198202164;6490.740370385131;6507.219133703986;6184.876302474016;6223;6267.655278959701;6300;6300.495960323072 18:31:47 INFO - PID 21025 | |1;ext.html;747.439420434674;812.6627449608413;830.7923019245187;840.4075127035441;1168.3924359333141;30684.386312273768;30751.692483075163;30934.226644333903;41644;42332;42318.788406058;42741.78629106858;42814.785926070406;42877.78561107198;42909.35635965463;7622.923770762304;7757.185495522974;7765.650545725456;7771.727989520368;7797.181295963927;7619.5809230492305;7721.961390193042;7736;7759.224077592252;7759.689612415525;7509.849803003936;7703.3452156566755;7704.152543220242;7705.614719264015;7720.189380115091;7525.623718814092;7617.238276172394;7643.044619422572;7670.731524396647;7686.769396918074;7481.7007319707145;7560.886586701149;7573.053368328959;7636.083669959585;7642.656080476392;37225.25549489022;37738.24523509542;37766;37971.430428543594;38007.61992380096;3592.497050412944;3597.928041439183;3644.6173151819207;3644.7084233261276;3650;30332.02841815275;30355.392892142256;30457.23856903595;30596.235094122603;30661.693383066107;4883.706977581321;4885.682430642032;4968.22992436173;4975.502449755032;5036.924446133311;212979;213715;215477;216266;217156;48310.27534586984;48456.75771621119;48547.514524855;48741.5125848737;48914.75542622335;50165.498345016815;50556.74721626442;50562;50615.493845061814;50806.491935080914;16565.25456354467;16644.334226631043;16705.83294167067;16750.246238919277;16764.916175419046;13431.798523022162;13606.183628982293;13723.656908577366;13743.656408589864;13750.381232844546;14191.71616567663;14322.856771432153;14337.713245735027;14444.85555144435;14491.116154899795;5705.74324155414;5712.457316554921;5897.941020589825;5902.822915312548;5974.910376344358;995.6365926436944;995.915347195482;1016.4714348538719;1043.3218408034838;1049.9265051446325;22092.447688807908;22352.770597616964;22679;22925.312240632808;23462.64806027911;1113;1114.3815182573749;1150.6317978247048;1151.8790526994544;1156.7917774800449;11774.3524106175;12039.277643341427;12041.591681663702;12195.695107622381;12271.097035875187;226762;228026;228503;228752;230861;51522;51678.22482662688;52002.219966700526;52112.21831672528;54076.45923540793;1446.3997441062052;1500.092444071343;1503.9624009399633;1505.2172870107484;1525.557588299403;26587.867060664572;27617.447651046463;27677.723222767516;27701.44597108087;27719.584206237327;38115.04712382213;38195.61804381921;38444.807775960944;38494;38555.036124097125;203893;204483;205677;205839;206310;34316.82841585776;34674.826625866706;35077.47383789194;35225.82387088048;35547.11132221715;40657.173717014455;42849.35725964175;43131.137377252904;43141.56858431501;43636;33478.83260583682;33764.3247135051;34555.65444345625;34611.82694086513;34795.65204348025;33023.66976330302;33294.83352583222;33962;34089.318213636085;34368;36525.45211821878;37502.43746343862;37664.62335376721;37825.81087094547;38359.61640383672;32885.50671739974;32911.506327404626;33168.17079572933;33591;33777.493337599466;23586.764132358458;23817.64273535933;23845.64231536563;24078.277651670476;24130.758692413554 18:31:47 INFO - PID 21025 | |2;jquery.html;551.5863102672995;555.4667519181586;563.1496440375035;582.7028215610038;586.2261814404984;21496.57006859862;22634.54730905381;22787.886060569715;22815.771842281592;22888.771112288872;24262.51474970504;25562.233133005993;26642;26744.866275668595;26840.19479415616;6519.739210431589;6661.26726060133;6689.963055726346;6694.062831203635;6699.028640847074;6596.60420374779;6637.435817955479;6643.568168069084;6651.634160121192;6662.400383965442;6170.691465426733;6310.968445157768;6412.390822871843;6426.6144031358035;6621.337866213388;6455.32219116993;6538.084668146464;6542.869142617121;6543.443807276387;6551.410373066423;6078.088286757022;6078.939210607881;6079.939200607982;6097.3597772233725;6116.204893363834;27164.1850744476;29316.853415732785;29696.8515157425;29825.552616710767;29902.70097299021;1883.6044430669576;1927.787943326233;1942.5823447958614;1944.397236856579;1947.191915355126;18555.536111597183;19040.71438928417;19077.950712710794;19183.90408047965;19235.13441895118;2306.5963456395066;2312.7571604981404;2343.7656234376595;2361.1027809432476;2387.283814855554;137565.18521851688;137789;140625.29687351498;141160;141380.29309853486;13953.86046139546;14456.204908729911;14498.710025799424;14850.777238341434;15116.244187790566;22118.778812211673;22212;22396.776032239468;22603.321900343017;22630.547389052128;10251.436171010517;10333.63832265882;10364;10500;10569.260151789455;9738.415695058318;9740.220782337396;9774.902250977542;9860.30977831545;9985.45080020606;8846.424982376253;8903.243224326005;8999.32505062123;9145.81708365829;9192.724218273463;2695.582184761365;3375.9493607595905;3454.844531996066;3661.8718344857803;3959.48526691534;969.6896992962185;979.7011911366959;985.1872205430549;994.9005099489993;1009.6718566465928;19588.90205548963;19841.702374464396;19859.702104468444;19870.403887883385;20226.797732022493;1009.4347165587294;1066.4880857188439;1079.2768844873954;1085.4084523934475;1088.842117892917;10309.15469729226;10811.729706757395;10908.290961087503;10922.508487118099;11066.667999960015;506717;510338;516739;518266;540597;122250.38874805569;122844;125536.23391298276;125959;129223;1270.4473554004037;1272.3574594829736;1309.646395473217;1322.841259048901;1325.6221976736583;19653.705194422382;20458.181672732924;20773.792262077186;20911.477213069185;21026.2640807571;54470;55327;57286;59298.40701592929;59532.40467595268;69662;71309;72466.27533724596;72523.63738181275;73344;13906.791398129239;14199.787003194753;14344.354504047134;14424;14435.133891966516;22533.211337603105;22774.886125569265;23020.539589207787;23062.42343941415;23310.533789324458;18016.549586259924;18097.185626647097;18435.07824608791;18567.814321856607;18692.06539673038;15392.846071539141;16177.352905884103;16520.66958660796;17099.316027358767;17601.031943243244;17531;17945.641087178443;18166.182521786817;18839.905800471457;20165.79834201639;14435.422583096559;14764.704705905608;14813.740832029398;14817.259137042889;14832.925835370754;16484.505464836075;17468.825311747227;17550.9122454392;17557;17650.646987060445 18:31:47 INFO - PID 21025 | |3;mootools.html;1357.511295933464;1415.7168566286746;1416.0866241274377;1433.7132573485303;1440.1143296872426;13408;14032.579022629337;14056.508022219237;14071.718565628707;14080.577582672524;13622.386992585332;14009.789853152211;14039.578812635636;14081.788773168411;14087.506937257172;2585.6509371234924;2650.986745066272;2652;2669.4260733942197;2672.1983404978528;2517.295157355943;2599.363156026776;2603.049886791317;2610;2616.1759045900576;2521.8991240350456;2540.16174662361;2600.4279058607076;2604.830686005413;2612.4644447888136;2544.224011676438;2635.4729054189143;2637.274749443899;2642.93392665183;2643.4845205184997;2503.3616427810975;2568.6403903453534;2573.6911570611646;2573.6911570611646;2577.5231582157176;13703.725925481434;13883.4446622135;13898.374573144132;13918.51285205013;13950.09324393911;7514.323710865993;7799.766007019798;7849.01887264092;7856.489328193649;7873.5275883447175;4822.252550854623;4917.114919314522;4993.725345106018;5126.615503837227;5129.1280482317725;1802.9092399098488;1824.4617837737899;1836.4674244469088;1903.809619038099;1904.2763749775136;22350.66474002891;22440.438989025242;22583.435414114778;22719.43201419961;22734.88632556843;6214.627122372672;6313.084602732578;6357.682115894187;6379.521535884827;6532.738690452328;15418.6145346365;15484.148371839658;15489.148096854557;15499.690006199813;15501.147436890853;7153.785386438415;7417.147028091731;7426.14599321085;7433.293837085468;7481.438892083007;6819.897701534481;6835.829104272433;6907.7236910524025;7034.788956331318;7068.823279418056;4569.565891240327;4763.666543342002;4797.232442809134;4850.805967761321;4886.120498310267;1253.8934190593902;1260.7415479826614;1274.7832868412393;1300.2263653126374;1304.210952373819;871.2333146830748;876.1982785751094;898.9910100899039;899.7660608241773;917.206616276929;6866.038754574363;6972.686229119702;7064.964675176591;7079.539829911039;7143.964280178566;703.7677566403074;708.2987842036347;721.6788529104498;728.7158008376738;734.7244783206297;4555.886102847389;5165.948340516622;5330.466953304638;5352.277442545284;5477.233187353773;17768.200430980636;18593.628127437376;18629;18698.252069917322;18826;17839.375621853433;18743.437696869114;18791.060446977597;18934.337298194772;19219.8078019218;10499.842502362471;10508.894911050944;10637.149028077734;10674.199435042403;11140.832887506695;7518.511296765692;7878.251566101211;7974.322182614543;8067.475614085066;8106.8783968241705;15664.451744188898;15895.920520397323;16152;16286.348546058025;16493.67012659716;15368.308426120628;15656.37374505007;16274.593135171242;16573.41993030238;16862.072586007398;11184.720381990515;11399.886001139883;11458.71353216143;11468;11491.827622585835;12950.481980721042;13267.137636053625;13419.530316438877;13450.663733406744;13452;9963.50182490887;10356.223283253636;10389.584416623251;10558.577656893945;10590.629327973793;9893.901060989298;10174.186065114922;10347.63783267582;10363.689089327332;10616.469176540988;9076.818463630558;9123.90876091257;9147.86278205814;9162.31282653817;9205.76985575366;7008.4042856355745;7068.3285087916565;7071.398931090709;7108.822279443055;7235.167955685163;7040.119985001875;7060.1880783708475;7148.785536433916;7151.2491188424465;7245.2030276670575 18:31:47 INFO - PID 21025 | |4;prototype.html;485.5917316105938;501.5297148966149;513.2967834067327;519.828456609319;521.7730287325014;5906;6007.687040489416;6055.666938318396;6076.756929722806;6160.6611636359985;5822.835432913413;5908.940910590893;5932.792352267671;6004.489618382443;6044.486218671407;4823.710577365369;4876.609871210312;4888.535589119046;4892.412910450733;4917.72952487613;4796.736179510125;4823.927641085369;4890.633202509808;4913.508649135094;4916.877078073059;4868.318435419044;4870.878228044309;4872.561469467747;4879.512048795127;4906.509349065101;4560.954390456086;4778.713277203379;4784.521547845223;4843.443004054526;4877.170880950247;4276.593723596254;4319.719218250835;4320.697551171419;4366.868993930156;4369.69412141147;5836.64980101191;5840.445157710011;5878.941210587925;5891.617044892111;5906.379830117861;5504.532114770249;5539.279893613845;5549.500544950974;5580.525655319303;5592.133219351007;6348.968255158695;6358.427741503287;6398.808035758934;6424.196975378078;6433.871322573522;4739.36018637483;4747.12178247022;4750.2874568814605;4753.738544380058;4756.167670657621;6688.789381097101;6698.966505167492;6766.699322230465;6773.593584384953;6801.557898736566;4928.876778080505;4956.776945037482;4960.8759781005765;4963.801447942042;4976.2038073908;5019.749012549358;5029.03287060636;5029.899402011939;5034.370703662043;5065.695108725595;5533.004059269289;5578.525825304894;5591.8602034948635;5600.4959553640365;5610.887782244332;6371.267304259976;6414.8717025659225;6476.967615161988;6510.544261901716;6517.51118666102;1689.341156948791;1725.30125299254;1726.8689008699323;1729;1745.877788554789;1123.702218911989;1180.2859270141612;1196.682879037056;1205.6684411786828;1206.3666575047994;5391.892162156813;5471;5483.711327837955;5500.80747173847;5620.156976453564;6545.14913061299;6589.341065893373;6595.835104122435;6622.503312251601;6641.236257830315;5673.290838645169;5685.744141513642;5688.943110568841;5702.800401985911;5707.200991861143;3405.676460736226;3441.7934923904145;3472.3576138414473;3498.7026102781037;3517.771344862576;6208.689565521706;6262.2172228471445;6286.15136956505;6358.745650173942;6379.585326953733;6683.899741503881;6689.899651505134;6707.960266158753;6751.426128779109;6826.283240259801;6327.335629758809;6349.587276827084;6384.297727249998;6423.325550817097;6435.22777266731;6522.249941256627;6549.181352330958;6562.376574225441;6596.8350791230605;6601.174853143357;6417.229932407954;6481.513886458629;6488.708008139551;6517.283098859217;6598.637074960923;6227.346128656607;6301.023341382093;6305.337939516468;6305.684715764101;6362.586431881913;4780.685311539354;4814.494478079891;4860.756962151949;4870.78081486341;4919.745464906426;6518.902216466851;6519.902201467076;6549.214094308713;6568.244651865097;6642.767503137367;6317.989121740499;6344.397282258178;6422;6440.456019872218;6456.54804163704;6406.167198264196;6411.839704007437;6434.163558737334;6444.323346048597;6452.387023232785;6258.780942666985;6273.498120150286;6325.367463253822;6349.4920406366455;6373.629669621054;6253.593516421418;6336.651484168415;6403.839904002437;6423.775167869102;6444.677766111769;5596.244506991504;6104.4505994460715;6113.419225173601;6220.253569571681;6273.177070318284 18:31:47 INFO - PID 21025 | |5;yui.html;899.2311573604572;913.8994710581837;926.0230456868002;926.3978414030879;930.562635561286;16672.74990875138;16722.163891805423;16768.580785480368;18430.631387372243;18950.62098758024;19678.70481942772;20608.587828243464;21243.15027398902;21367.57264854706;21708.782912170875;1025.7948410317947;1059.3167407022465;1061.257120015988;1061.723951772537;1061.7345663584103;1054.1619412567027;1065.637683187716;1066.9306495077833;1068.4871261794344;1074.2856000759525;1009.13814013146;1042.5048102151493;1097.4841824342545;1104.3373975614647;1129.8870112988718;1080.9243352965295;1090.5038207615555;1091.579741799413;1091.7379828841101;1092.9016388525072;787.7518581646763;788.3771820261986;789.124072279769;789.7670187294761;791.1297572670074;19448.70826937597;19545.21819127233;19775.80224197754;19813;20164.092615832324;20200.29298974529;20458.897705511525;20754.48113797167;20850.47873803167;21123.894380528152;16173.35306587735;16213.675726485522;16368.427105051385;16390.0165990041;16470.42353517633;3367.9831600842085;3383.2387712764676;3384.221629025313;3386.255023894739;3393.117789374756;117501.41249293784;118191;122238;123214.38392807978;128342.99999999907;15236.542903712907;15966.600834978986;16014.599635008984;16113.194340282938;16131.919340403223;29525;30044.54933176004;30223;30279.848600756854;30464.086077417713;8609.397342186112;9029.45823250594;9100.635974561075;9101.81796364069;9103.135202155785;4218.873433796991;4248.150369926027;4252.787360631956;4256.361545768098;4266.168097221012;5072.923906141411;5124.975004998941;5145.845624631267;5148.150555158431;5185.48145185486;940.9294302927308;956.8899576548647;960.4045491794992;966.246327864267;981.9901800982042;608.4706305514231;704.5807744392079;710.9964450177716;727.31268950842;732.2201855024382;11376.317420954769;12282.570110046105;12884.291363975071;12967.675808104872;13209.141405808592;703.9225685174627;706.1420374245316;708.5040471669852;712.8788106021989;729.8978143059976;5499.642523235977;5590.468905453975;5639.859003524863;5701.116326969326;5770.798022069207;98809;100073;103612;107372.46313768381;109869;91951;95657.52171239238;95742.52128739312;97123.51438242904;99025.50487247662;82350.9999999988;82536;83952.58023709842;86114;89585;12299.385030748606;12367.443465043914;12877.935610321889;12892.290923998895;12952.926100809202;35477.29045419129;36349.00965362848;36410;36967.81516092402;38672.41991370075;65237.67381163064;67349;69234.30765692481;69469.65265173641;74237.25762742519;18050.819491804912;18481;18651.626967460303;18739.531511712317;19008.904955475133;19371;19534.120964556343;19606.90196549008;20012;20074.698879517113;17678.911605441892;18502.057283985527;18887.338943136925;19211.807881921002;19296.710549342053;18475.168617412517;18590.53523661919;19483.123259453074;20214;20323.187072516932;18261.90869045646;19113.139908703863;19321.90339048296;19622.705659415406;19633.410997670093;12319.938400307941;12960.740785184054;13054.477820887438;13105.27920964356;13219.933900330438;14916.179610121113;15042.097474151586;15068.54794356171;15394.15332156742;15444.92277538605 18:31:47 INFO - PID 21025 | __end_tp_report 18:31:47 INFO - PID 21025 | __start_cc_report 18:31:47 INFO - PID 21025 | _x_x_mozilla_cycle_collect,372 18:31:47 INFO - PID 21025 | __end_cc_report 18:31:47 INFO - PID 21025 | __startTimestamp1500341507513__endTimestamp 18:31:47 INFO - PID 21025 | 18:31:47 INFO - PID 21025 | ------- Summary: start ------- 18:31:47 INFO - PID 21025 | Number of tests: 6 18:31:47 INFO - PID 21025 | 18:31:47 INFO - PID 21025 | [#0] dojo.html Cycles:165 Average:6010.44 Median:6285.75 stddev:691.76 (11.0%) stddev-sans-first:653.99 18:31:47 INFO - PID 21025 | Values: 3059.3 3119.8 3128.2 3154.7 3175.8 5981.3 6000.3 6034.0 6041.7 6105.0 6054.5 6070.5 6073.2 6109.9 6110.3 5596.9 5622.2 5639.2 5644.9 5664.0 5599.6 5633.0 5639.5 5665.8 5666.5 5618.8 5633.1 5633.4 5639.5 5642.6 5626.2 5631.0 5639.8 5642.8 5663.6 5492.9 5586.4 5603.4 5625.9 5683.8 5954.6 6002.8 6024.5 6049.0 6054.0 6368.5 6372.7 6453.6 6456.3 6464.3 6532.6 6557.7 6570.2 6582.1 6589.0 6008.1 6017.8 6023.9 6041.7 6103.7 6576.8 6690.1 6742.3 6756.4 6826.8 6326.3 6406.3 6429.3 6481.5 6520.1 6520.2 6545.7 6614.4 6624.6 6626.7 6349.9 6408.3 6469.8 6471.9 6527.0 6426.0 6437.8 6521.5 6537.6 6583.3 5258.8 5303.4 5352.7 5368.1 5376.5 4511.8 4546.4 4557.6 4608.7 4632.9 5642.7 5659.3 5666.9 5689.5 5690.6 6559.1 6576.2 6577.3 6582.9 6588.2 5591.1 5665.4 5670.7 5672.7 5702.2 5710.3 5729.5 5787.8 5799.5 5821.9 6309.9 6319.4 6345.5 6349.8 6353.4 6439.3 6477.2 6508.8 6566.4 6569.4 6264.4 6292.6 6327.0 6339.1 6408.1 6331.4 6340.0 6344.1 6441.0 6445.8 6472.4 6497.9 6559.5 6561.9 6592.3 6282.1 6285.7 6355.2 6377.6 6393.6 6386.0 6399.8 6405.4 6406.8 6421.5 5378.8 6270.8 6314.1 6336.5 6338.1 6356.1 6359.7 6422.4 6490.7 6507.2 6184.9 6223.0 6267.7 6300.0 6300.5 18:31:47 INFO - PID 21025 | 18:31:47 INFO - PID 21025 | [#1] ext.html Cycles:180 Average:38597.84 Median:35386.47 stddev:56058.06 (158.4%) stddev-sans-first:56143.27 18:31:47 INFO - PID 21025 | Values: 747.4 812.7 830.8 840.4 1168.4 30684.4 30751.7 30934.2 41644.0 42332.0 42318.8 42741.8 42814.8 42877.8 42909.4 7622.9 7757.2 7765.7 7771.7 7797.2 7619.6 7722.0 7736.0 7759.2 7759.7 7509.8 7703.3 7704.2 7705.6 7720.2 7525.6 7617.2 7643.0 7670.7 7686.8 7481.7 7560.9 7573.1 7636.1 7642.7 37225.3 37738.2 37766.0 37971.4 38007.6 3592.5 3597.9 3644.6 3644.7 3650.0 30332.0 30355.4 30457.2 30596.2 30661.7 4883.7 4885.7 4968.2 4975.5 5036.9 212979.0 213715.0 215477.0 216266.0 217156.0 48310.3 48456.8 48547.5 48741.5 48914.8 50165.5 50556.7 50562.0 50615.5 50806.5 16565.3 16644.3 16705.8 16750.2 16764.9 13431.8 13606.2 13723.7 13743.7 13750.4 14191.7 14322.9 14337.7 14444.9 14491.1 5705.7 5712.5 5897.9 5902.8 5974.9 995.6 995.9 1016.5 1043.3 1049.9 22092.4 22352.8 22679.0 22925.3 23462.6 1113.0 1114.4 1150.6 1151.9 1156.8 11774.4 12039.3 12041.6 12195.7 12271.1 226762.0 228026.0 228503.0 228752.0 230861.0 51522.0 51678.2 52002.2 52112.2 54076.5 1446.4 1500.1 1504.0 1505.2 1525.6 26587.9 27617.4 27677.7 27701.4 27719.6 38115.0 38195.6 38444.8 38494.0 38555.0 203893.0 204483.0 205677.0 205839.0 206310.0 34316.8 34674.8 35077.5 35225.8 35547.1 40657.2 42849.4 43131.1 43141.6 43636.0 33478.8 33764.3 34555.7 34611.8 34795.7 33023.7 33294.8 33962.0 34089.3 34368.0 36525.5 37502.4 37664.6 37825.8 38359.6 32885.5 32911.5 33168.2 33591.0 33777.5 23586.8 23817.6 23845.6 24078.3 24130.8 18:31:47 INFO - PID 21025 | 18:31:47 INFO - PID 21025 | [#2] jquery.html Cycles:180 Average:35924.35 Median:22568.27 stddev:87318.59 (386.9%) stddev-sans-first:87523.15 18:31:47 INFO - PID 21025 | Values: 551.6 555.5 563.1 582.7 586.2 21496.6 22634.5 22787.9 22815.8 22888.8 24262.5 25562.2 26642.0 26744.9 26840.2 6519.7 6661.3 6690.0 6694.1 6699.0 6596.6 6637.4 6643.6 6651.6 6662.4 6170.7 6311.0 6412.4 6426.6 6621.3 6455.3 6538.1 6542.9 6543.4 6551.4 6078.1 6078.9 6079.9 6097.4 6116.2 27164.2 29316.9 29696.9 29825.6 29902.7 1883.6 1927.8 1942.6 1944.4 1947.2 18555.5 19040.7 19078.0 19183.9 19235.1 2306.6 2312.8 2343.8 2361.1 2387.3 137565.2 137789.0 140625.3 141160.0 141380.3 13953.9 14456.2 14498.7 14850.8 15116.2 22118.8 22212.0 22396.8 22603.3 22630.5 10251.4 10333.6 10364.0 10500.0 10569.3 9738.4 9740.2 9774.9 9860.3 9985.5 8846.4 8903.2 8999.3 9145.8 9192.7 2695.6 3375.9 3454.8 3661.9 3959.5 969.7 979.7 985.2 994.9 1009.7 19588.9 19841.7 19859.7 19870.4 20226.8 1009.4 1066.5 1079.3 1085.4 1088.8 10309.2 10811.7 10908.3 10922.5 11066.7 506717.0 510338.0 516739.0 518266.0 540597.0 122250.4 122844.0 125536.2 125959.0 129223.0 1270.4 1272.4 1309.6 1322.8 1325.6 19653.7 20458.2 20773.8 20911.5 21026.3 54470.0 55327.0 57286.0 59298.4 59532.4 69662.0 71309.0 72466.3 72523.6 73344.0 13906.8 14199.8 14344.4 14424.0 14435.1 22533.2 22774.9 23020.5 23062.4 23310.5 18016.5 18097.2 18435.1 18567.8 18692.1 15392.8 16177.4 16520.7 17099.3 17601.0 17531.0 17945.6 18166.2 18839.9 20165.8 14435.4 14764.7 14813.7 14817.3 14832.9 16484.5 17468.8 17550.9 17557.0 17650.6 18:31:47 INFO - PID 21025 | 18:31:47 INFO - PID 21025 | [#3] mootools.html Cycles:180 Average:8504.86 Median:2575.61 stddev:5768.96 (224.0%) stddev-sans-first:5760.15 18:31:47 INFO - PID 21025 | Values: 1357.5 1415.7 1416.1 1433.7 1440.1 13408.0 14032.6 14056.5 14071.7 14080.6 13622.4 14009.8 14039.6 14081.8 14087.5 2585.7 2651.0 2652.0 2669.4 2672.2 2517.3 2599.4 2603.0 2610.0 2616.2 2521.9 2540.2 2600.4 2604.8 2612.5 2544.2 2635.5 2637.3 2642.9 2643.5 2503.4 2568.6 2573.7 2573.7 2577.5 13703.7 13883.4 13898.4 13918.5 13950.1 7514.3 7799.8 7849.0 7856.5 7873.5 4822.3 4917.1 4993.7 5126.6 5129.1 1802.9 1824.5 1836.5 1903.8 1904.3 22350.7 22440.4 22583.4 22719.4 22734.9 6214.6 6313.1 6357.7 6379.5 6532.7 15418.6 15484.1 15489.1 15499.7 15501.1 7153.8 7417.1 7426.1 7433.3 7481.4 6819.9 6835.8 6907.7 7034.8 7068.8 4569.6 4763.7 4797.2 4850.8 4886.1 1253.9 1260.7 1274.8 1300.2 1304.2 871.2 876.2 899.0 899.8 917.2 6866.0 6972.7 7065.0 7079.5 7144.0 703.8 708.3 721.7 728.7 734.7 4555.9 5165.9 5330.5 5352.3 5477.2 17768.2 18593.6 18629.0 18698.3 18826.0 17839.4 18743.4 18791.1 18934.3 19219.8 10499.8 10508.9 10637.1 10674.2 11140.8 7518.5 7878.3 7974.3 8067.5 8106.9 15664.5 15895.9 16152.0 16286.3 16493.7 15368.3 15656.4 16274.6 16573.4 16862.1 11184.7 11399.9 11458.7 11468.0 11491.8 12950.5 13267.1 13419.5 13450.7 13452.0 9963.5 10356.2 10389.6 10558.6 10590.6 9893.9 10174.2 10347.6 10363.7 10616.5 9076.8 9123.9 9147.9 9162.3 9205.8 7008.4 7068.3 7071.4 7108.8 7235.2 7040.1 7060.2 7148.8 7151.2 7245.2 18:31:47 INFO - PID 21025 | 18:31:47 INFO - PID 21025 | [#4] prototype.html Cycles:180 Average:5374.75 Median:5898.81 stddev:1508.72 (25.6%) stddev-sans-first:1467.64 18:31:47 INFO - PID 21025 | Values: 485.6 501.5 513.3 519.8 521.8 5906.0 6007.7 6055.7 6076.8 6160.7 5822.8 5908.9 5932.8 6004.5 6044.5 4823.7 4876.6 4888.5 4892.4 4917.7 4796.7 4823.9 4890.6 4913.5 4916.9 4868.3 4870.9 4872.6 4879.5 4906.5 4561.0 4778.7 4784.5 4843.4 4877.2 4276.6 4319.7 4320.7 4366.9 4369.7 5836.6 5840.4 5878.9 5891.6 5906.4 5504.5 5539.3 5549.5 5580.5 5592.1 6349.0 6358.4 6398.8 6424.2 6433.9 4739.4 4747.1 4750.3 4753.7 4756.2 6688.8 6699.0 6766.7 6773.6 6801.6 4928.9 4956.8 4960.9 4963.8 4976.2 5019.7 5029.0 5029.9 5034.4 5065.7 5533.0 5578.5 5591.9 5600.5 5610.9 6371.3 6414.9 6477.0 6510.5 6517.5 1689.3 1725.3 1726.9 1729.0 1745.9 1123.7 1180.3 1196.7 1205.7 1206.4 5391.9 5471.0 5483.7 5500.8 5620.2 6545.1 6589.3 6595.8 6622.5 6641.2 5673.3 5685.7 5688.9 5702.8 5707.2 3405.7 3441.8 3472.4 3498.7 3517.8 6208.7 6262.2 6286.2 6358.7 6379.6 6683.9 6689.9 6708.0 6751.4 6826.3 6327.3 6349.6 6384.3 6423.3 6435.2 6522.2 6549.2 6562.4 6596.8 6601.2 6417.2 6481.5 6488.7 6517.3 6598.6 6227.3 6301.0 6305.3 6305.7 6362.6 4780.7 4814.5 4860.8 4870.8 4919.7 6518.9 6519.9 6549.2 6568.2 6642.8 6318.0 6344.4 6422.0 6440.5 6456.5 6406.2 6411.8 6434.2 6444.3 6452.4 6258.8 6273.5 6325.4 6349.5 6373.6 6253.6 6336.7 6403.8 6423.8 6444.7 5596.2 6104.5 6113.4 6220.3 6273.2 18:31:47 INFO - PID 21025 | 18:31:47 INFO - PID 21025 | [#5] yui.html Cycles:180 Average:23289.75 Median:20043.35 stddev:31016.37 (154.7%) stddev-sans-first:31057.81 18:31:47 INFO - PID 21025 | Values: 899.2 913.9 926.0 926.4 930.6 16672.7 16722.2 16768.6 18430.6 18950.6 19678.7 20608.6 21243.2 21367.6 21708.8 1025.8 1059.3 1061.3 1061.7 1061.7 1054.2 1065.6 1066.9 1068.5 1074.3 1009.1 1042.5 1097.5 1104.3 1129.9 1080.9 1090.5 1091.6 1091.7 1092.9 787.8 788.4 789.1 789.8 791.1 19448.7 19545.2 19775.8 19813.0 20164.1 20200.3 20458.9 20754.5 20850.5 21123.9 16173.4 16213.7 16368.4 16390.0 16470.4 3368.0 3383.2 3384.2 3386.3 3393.1 117501.4 118191.0 122238.0 123214.4 128343.0 15236.5 15966.6 16014.6 16113.2 16131.9 29525.0 30044.5 30223.0 30279.8 30464.1 8609.4 9029.5 9100.6 9101.8 9103.1 4218.9 4248.2 4252.8 4256.4 4266.2 5072.9 5125.0 5145.8 5148.2 5185.5 940.9 956.9 960.4 966.2 982.0 608.5 704.6 711.0 727.3 732.2 11376.3 12282.6 12884.3 12967.7 13209.1 703.9 706.1 708.5 712.9 729.9 5499.6 5590.5 5639.9 5701.1 5770.8 98809.0 100073.0 103612.0 107372.5 109869.0 91951.0 95657.5 95742.5 97123.5 99025.5 82351.0 82536.0 83952.6 86114.0 89585.0 12299.4 12367.4 12877.9 12892.3 12952.9 35477.3 36349.0 36410.0 36967.8 38672.4 65237.7 67349.0 69234.3 69469.7 74237.3 18050.8 18481.0 18651.6 18739.5 19008.9 19371.0 19534.1 19606.9 20012.0 20074.7 17678.9 18502.1 18887.3 19211.8 19296.7 18475.2 18590.5 19483.1 20214.0 20323.2 18261.9 19113.1 19321.9 19622.7 19633.4 12319.9 12960.7 13054.5 13105.3 13219.9 14916.2 15042.1 15068.5 15394.2 15444.9 18:31:47 INFO - PID 21025 | -------- Summary: end -------- 18:31:47 INFO - PID 21025 | 18:31:48 INFO - TEST-INFO | 21025: exit 0 18:31:48 INFO - TEST-OK | dromaeo_css | took 1110870ms 18:31:48 INFO - TEST-START | kraken 18:31:48 INFO - Initialising browser for kraken test... 18:31:48 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:46988/getInfo.html -profile /tmp/tmp7U6Xta/profile 18:31:48 INFO - TEST-INFO | started process 21434 (/builds/slave/test/build/application/firefox/firefox http://localhost:46988/getInfo.html) 18:31:54 INFO - TEST-INFO | 21434: exit 0 18:31:54 INFO - Browser initialized. 18:31:54 INFO - Running cycle 1/1 for kraken test... 18:31:54 INFO - TEST-INFO | started process 21637 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7U6Xta/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:32:05 INFO - PID 21637 | RSS: Main: 168472576 18:32:05 INFO - PID 21637 | 18:32:08 INFO - PID 21637 | Cycle 1(1): loaded http://localhost:46988/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46988/tests/kraken/driver.html?testName=audio-beat-detection) 18:32:09 INFO - PID 21637 | RSS: Main: 174731264 18:32:09 INFO - PID 21637 | 18:32:11 INFO - PID 21637 | Cycle 1(1): loaded http://localhost:46988/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46988/tests/kraken/driver.html?testName=audio-dft) 18:32:12 INFO - PID 21637 | RSS: Main: 175476736 18:32:12 INFO - PID 21637 | 18:32:15 INFO - PID 21637 | Cycle 1(1): loaded http://localhost:46988/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46988/tests/kraken/driver.html?testName=audio-fft) 18:32:16 INFO - PID 21637 | RSS: Main: 168681472 18:32:16 INFO - PID 21637 | 18:32:18 INFO - PID 21637 | Cycle 1(1): loaded http://localhost:46988/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46988/tests/kraken/driver.html?testName=audio-oscillator) 18:32:18 INFO - PID 21637 | RSS: Main: 168988672 18:32:18 INFO - PID 21637 | 18:32:20 INFO - PID 21637 | Cycle 1(1): loaded http://localhost:46988/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46988/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:32:21 INFO - PID 21637 | RSS: Main: 169205760 18:32:21 INFO - PID 21637 | 18:32:24 INFO - PID 21637 | Cycle 1(1): loaded http://localhost:46988/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46988/tests/kraken/driver.html?testName=imaging-darkroom) 18:32:25 INFO - PID 21637 | RSS: Main: 172847104 18:32:25 INFO - PID 21637 | 18:32:28 INFO - PID 21637 | Cycle 1(1): loaded http://localhost:46988/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46988/tests/kraken/driver.html?testName=imaging-desaturate) 18:32:29 INFO - PID 21637 | RSS: Main: 173510656 18:32:29 INFO - PID 21637 | 18:32:31 INFO - PID 21637 | Cycle 1(1): loaded http://localhost:46988/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46988/tests/kraken/driver.html?testName=json-parse-financial) 18:32:32 INFO - PID 21637 | RSS: Main: 172900352 18:32:32 INFO - PID 21637 | 18:32:34 INFO - PID 21637 | Cycle 1(1): loaded http://localhost:46988/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46988/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:32:34 INFO - PID 21637 | RSS: Main: 172830720 18:32:34 INFO - PID 21637 | 18:32:36 INFO - PID 21637 | Cycle 1(1): loaded http://localhost:46988/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46988/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:32:37 INFO - PID 21637 | RSS: Main: 173535232 18:32:37 INFO - PID 21637 | 18:32:39 INFO - PID 21637 | Cycle 1(1): loaded http://localhost:46988/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46988/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:32:39 INFO - PID 21637 | RSS: Main: 172490752 18:32:39 INFO - PID 21637 | 18:32:42 INFO - PID 21637 | Cycle 1(1): loaded http://localhost:46988/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46988/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:32:43 INFO - PID 21637 | RSS: Main: 173486080 18:32:43 INFO - PID 21637 | 18:32:46 INFO - PID 21637 | Cycle 1(1): loaded http://localhost:46988/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46988/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:32:46 INFO - PID 21637 | RSS: Main: 172847104 18:32:46 INFO - PID 21637 | 18:32:48 INFO - PID 21637 | Cycle 1(1): loaded http://localhost:46988/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46988/tests/kraken/driver.html?testName=ai-astar) 18:32:48 INFO - PID 21637 | RSS: Main: 174694400 18:32:48 INFO - PID 21637 | 18:32:48 INFO - PID 21637 | __start_tp_report 18:32:48 INFO - PID 21637 | _x_x_mozilla_page_load 18:32:48 INFO - PID 21637 | _x_x_mozilla_page_load_details 18:32:48 INFO - PID 21637 | |i|pagename|runs| 18:32:48 INFO - PID 21637 | |0;ai-astar;101;110;102;111;110;110;110;101;109;110 18:32:48 INFO - PID 21637 | |1;audio-beat-detection;146;120;160;172;124;157;147;127;177;129 18:32:48 INFO - PID 21637 | |2;audio-dft;164;163;162;163;163;163;164;164;178;165 18:32:48 INFO - PID 21637 | |3;audio-fft;75;75;76;163;75;75;75;77;96;75 18:32:48 INFO - PID 21637 | |4;audio-oscillator;83;84;135;83;81;83;133;84;82;127 18:32:48 INFO - PID 21637 | |5;imaging-gaussian-blur;114;113;114;113;113;114;114;113;114;115 18:32:48 INFO - PID 21637 | |6;imaging-darkroom;150;150;150;150;150;151;151;150;150;150 18:32:48 INFO - PID 21637 | |7;imaging-desaturate;95;93;95;94;94;94;94;94;93;94 18:32:48 INFO - PID 21637 | |8;json-parse-financial;54;55;56;55;55;58;55;57;55;54 18:32:48 INFO - PID 21637 | |9;json-stringify-tinderbox;54;54;54;55;55;54;55;54;55;54 18:32:48 INFO - PID 21637 | |10;stanford-crypto-aes;77;78;79;77;80;77;80;76;87;76 18:32:48 INFO - PID 21637 | |11;stanford-crypto-ccm;130;130;131;133;152;126;133;132;126;163 18:32:48 INFO - PID 21637 | |12;stanford-crypto-pbkdf2;179;184;183;188;180;204;185;186;185;179 18:32:48 INFO - PID 21637 | |13;stanford-crypto-sha256-iterative;67;73;68;69;69;68;68;73;67;69 18:32:48 INFO - PID 21637 | __end_tp_report 18:32:48 INFO - PID 21637 | __start_cc_report 18:32:48 INFO - PID 21637 | _x_x_mozilla_cycle_collect,944 18:32:48 INFO - PID 21637 | __end_cc_report 18:32:48 INFO - PID 21637 | __startTimestamp1500341568792__endTimestamp 18:32:48 INFO - PID 21637 | 18:32:48 INFO - PID 21637 | ------- Summary: start ------- 18:32:48 INFO - PID 21637 | Number of tests: 14 18:32:48 INFO - PID 21637 | 18:32:48 INFO - PID 21637 | [#0] ai-astar Cycles:10 Average:107.40 Median:110.00 stddev:4.22 (3.8%) stddev-sans-first:3.79 18:32:48 INFO - PID 21637 | Values: 101.0 110.0 102.0 111.0 110.0 110.0 110.0 101.0 109.0 110.0 18:32:48 INFO - PID 21637 | 18:32:48 INFO - PID 21637 | [#1] audio-beat-detection Cycles:10 Average:145.90 Median:152.00 stddev:20.45 (13.5%) stddev-sans-first:21.69 18:32:48 INFO - PID 21637 | Values: 146.0 120.0 160.0 172.0 124.0 157.0 147.0 127.0 177.0 129.0 18:32:48 INFO - PID 21637 | 18:32:48 INFO - PID 21637 | [#2] audio-dft Cycles:10 Average:164.90 Median:164.00 stddev:4.68 (2.9%) stddev-sans-first:4.95 18:32:48 INFO - PID 21637 | Values: 164.0 163.0 162.0 163.0 163.0 163.0 164.0 164.0 178.0 165.0 18:32:48 INFO - PID 21637 | 18:32:48 INFO - PID 21637 | [#3] audio-fft Cycles:10 Average:86.20 Median:75.00 stddev:27.76 (37.0%) stddev-sans-first:29.15 18:32:48 INFO - PID 21637 | Values: 75.0 75.0 76.0 163.0 75.0 75.0 75.0 77.0 96.0 75.0 18:32:48 INFO - PID 21637 | 18:32:48 INFO - PID 21637 | [#4] audio-oscillator Cycles:10 Average:97.50 Median:83.00 stddev:23.67 (28.5%) stddev-sans-first:24.52 18:32:48 INFO - PID 21637 | Values: 83.0 84.0 135.0 83.0 81.0 83.0 133.0 84.0 82.0 127.0 18:32:48 INFO - PID 21637 | 18:32:48 INFO - PID 21637 | [#5] imaging-gaussian-blur Cycles:10 Average:113.70 Median:114.00 stddev:0.67 (0.6%) stddev-sans-first:0.71 18:32:48 INFO - PID 21637 | Values: 114.0 113.0 114.0 113.0 113.0 114.0 114.0 113.0 114.0 115.0 18:32:48 INFO - PID 21637 | 18:32:48 INFO - PID 21637 | [#6] imaging-darkroom Cycles:10 Average:150.20 Median:150.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 18:32:48 INFO - PID 21637 | Values: 150.0 150.0 150.0 150.0 150.0 151.0 151.0 150.0 150.0 150.0 18:32:48 INFO - PID 21637 | 18:32:48 INFO - PID 21637 | [#7] imaging-desaturate Cycles:10 Average:94.00 Median:94.00 stddev:0.67 (0.7%) stddev-sans-first:0.60 18:32:48 INFO - PID 21637 | Values: 95.0 93.0 95.0 94.0 94.0 94.0 94.0 94.0 93.0 94.0 18:32:48 INFO - PID 21637 | 18:32:48 INFO - PID 21637 | [#8] json-parse-financial Cycles:10 Average:55.40 Median:55.00 stddev:1.26 (2.3%) stddev-sans-first:1.24 18:32:48 INFO - PID 21637 | Values: 54.0 55.0 56.0 55.0 55.0 58.0 55.0 57.0 55.0 54.0 18:32:48 INFO - PID 21637 | 18:32:48 INFO - PID 21637 | [#9] json-stringify-tinderbox Cycles:10 Average:54.40 Median:54.50 stddev:0.52 (0.9%) stddev-sans-first:0.53 18:32:48 INFO - PID 21637 | Values: 54.0 54.0 54.0 55.0 55.0 54.0 55.0 54.0 55.0 54.0 18:32:48 INFO - PID 21637 | 18:32:48 INFO - PID 21637 | [#10] stanford-crypto-aes Cycles:10 Average:78.70 Median:78.50 stddev:3.27 (4.2%) stddev-sans-first:3.41 18:32:48 INFO - PID 21637 | Values: 77.0 78.0 79.0 77.0 80.0 77.0 80.0 76.0 87.0 76.0 18:32:48 INFO - PID 21637 | 18:32:48 INFO - PID 21637 | [#11] stanford-crypto-ccm Cycles:10 Average:135.60 Median:132.50 stddev:12.08 (9.1%) stddev-sans-first:12.65 18:32:48 INFO - PID 21637 | Values: 130.0 130.0 131.0 133.0 152.0 126.0 133.0 132.0 126.0 163.0 18:32:48 INFO - PID 21637 | 18:32:48 INFO - PID 21637 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.30 Median:185.00 stddev:7.24 (3.9%) stddev-sans-first:7.31 18:32:48 INFO - PID 21637 | Values: 179.0 184.0 183.0 188.0 180.0 204.0 185.0 186.0 185.0 179.0 18:32:48 INFO - PID 21637 | 18:32:48 INFO - PID 21637 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.10 Median:69.00 stddev:2.18 (3.2%) stddev-sans-first:2.18 18:32:48 INFO - PID 21637 | Values: 67.0 73.0 68.0 69.0 69.0 68.0 68.0 73.0 67.0 69.0 18:32:48 INFO - PID 21637 | -------- Summary: end -------- 18:32:48 INFO - PID 21637 | 18:32:49 INFO - PID 21637 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 18:32:49 INFO - TEST-INFO | 21637: exit 0 18:32:49 INFO - TEST-OK | kraken | took 61419ms 18:32:49 INFO - SUITE-END | took 1172s 18:32:49 INFO - Completed test suite (00:19:32) 18:32:49 INFO - javascript benchmark 18:32:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8399.864460260442, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3059.281068948798, 3119.7660175486835, 3128.2335827722204, 3154.7476201903837, 3175.825329606872, 5981.252343457068, 6000.33996260411, 6033.969830150849, 6041.697915104243, 6104.9694751526295, 6054.485368743651, 6070.453659170674, 6073.180120683706, 6109.908351374733, 6110.2973158086725, 5596.944030559704, 5622.2128901953865, 5639.182318563806, 5644.915326270109, 5664.0371136906615, 5599.552035837143, 5633.014222511044, 5639.464250896158, 5665.773369065232, 5666.546676265889, 5618.803341883035, 5633.126865335859, 5633.352164501073, 5639.548836093123, 5642.633228840113, 5626.212330273765, 5630.971845140789, 5639.774409023634, 5642.802501912413, 5663.603547751639, 5492.862678433031, 5586.441355864381, 5603.439656034405, 5625.887482250332, 5683.829485115453, 5954.55340849438, 6002.759889604411, 6024.548158888101, 6049.032154855246, 6054.031354983182, 6368.490520758374, 6372.731745866091, 6453.5805172664095, 6456.2575303840185, 6464.288928217892, 6532.608043517404, 6557.704903279364, 6570.178727659043, 6582.078509008743, 6589.044588534635, 6008.068749343815, 6017.789377371771, 6023.909641355383, 6041.667708276043, 6103.6948152592195, 6576.769813056568, 6690.13028306317, 6742.258351581322, 6756.358145976124, 6826.829329266808, 6326.304106548276, 6406.327335629691, 6429.292777794439, 6481.48148148147, 6520.054592084121, 6520.217573891068, 6545.672716364258, 6614.404703576702, 6624.56940298879, 6626.668666566652, 6349.936500635027, 6408.2630497492455, 6469.838254043592, 6471.870562588721, 6526.967365163144, 6426.0, 6437.839054023687, 6521.47828173745, 6537.5750576212395, 6583.341665833378, 5258.789648414097, 5303.443138470482, 5352.678839269656, 5368.114261146867, 5376.489233522809, 4511.842085526991, 4546.431696037996, 4557.5898169164275, 4608.746518941423, 4632.930506042412, 5642.689652069093, 5659.320881494247, 5666.886662266732, 5689.516391106719, 5690.6301090429815, 6559.147310849559, 6576.177977752781, 6577.276499585042, 6582.868342633216, 6588.209414870246, 5591.07747221712, 5665.3768085510565, 5670.744816483186, 5672.716364181774, 5702.201691763156, 5710.343310519261, 5729.4843464088435, 5787.768489260382, 5799.478046975708, 5821.88356232873, 6309.936900630935, 6319.368063193736, 6345.460635845912, 6349.809505714836, 6353.396427339396, 6439.323870993572, 6477.222733271849, 6508.837279067871, 6566.4418524425955, 6569.408753212234, 6264.404881536247, 6292.622442653455, 6326.9683651581445, 6339.1125242467015, 6408.102865598913, 6331.430171284606, 6340.048992651139, 6344.143540622142, 6441.033844923111, 6445.838854028687, 6472.449841763503, 6497.902531462127, 6559.540832141704, 6561.868762624817, 6592.274849766618, 6282.089097080989, 6285.74857005733, 6355.205599300088, 6377.617342959437, 6393.584417012879, 6386.0, 6399.77600783989, 6405.423511883766, 6406.775762848278, 6421.518386120966, 5378.7579558920725, 6270.843228919313, 6314.1160237565855, 6336.461400780985, 6338.080978258219, 6356.078368636429, 6359.713812878525, 6422.42198202164, 6490.740370385131, 6507.219133703986, 6184.876302474016, 6223.0, 6267.655278959701, 6300.0, 6300.495960323072], "value": 5960.189917903588, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [747.439420434674, 812.6627449608413, 830.7923019245187, 840.4075127035441, 1168.3924359333141, 30684.386312273768, 30751.692483075163, 30934.226644333903, 41644.0, 42332.0, 42318.788406058, 42741.78629106858, 42814.785926070406, 42877.78561107198, 42909.35635965463, 7622.923770762304, 7757.185495522974, 7765.650545725456, 7771.727989520368, 7797.181295963927, 7619.5809230492305, 7721.961390193042, 7736.0, 7759.224077592252, 7759.689612415525, 7509.849803003936, 7703.3452156566755, 7704.152543220242, 7705.614719264015, 7720.189380115091, 7525.623718814092, 7617.238276172394, 7643.044619422572, 7670.731524396647, 7686.769396918074, 7481.7007319707145, 7560.886586701149, 7573.053368328959, 7636.083669959585, 7642.656080476392, 37225.25549489022, 37738.24523509542, 37766.0, 37971.430428543594, 38007.61992380096, 3592.497050412944, 3597.928041439183, 3644.6173151819207, 3644.7084233261276, 3650.0, 30332.02841815275, 30355.392892142256, 30457.23856903595, 30596.235094122603, 30661.693383066107, 4883.706977581321, 4885.682430642032, 4968.22992436173, 4975.502449755032, 5036.924446133311, 212979.0, 213715.0, 215477.0, 216266.0, 217156.0, 48310.27534586984, 48456.75771621119, 48547.514524855, 48741.5125848737, 48914.75542622335, 50165.498345016815, 50556.74721626442, 50562.0, 50615.493845061814, 50806.491935080914, 16565.25456354467, 16644.334226631043, 16705.83294167067, 16750.246238919277, 16764.916175419046, 13431.798523022162, 13606.183628982293, 13723.656908577366, 13743.656408589864, 13750.381232844546, 14191.71616567663, 14322.856771432153, 14337.713245735027, 14444.85555144435, 14491.116154899795, 5705.74324155414, 5712.457316554921, 5897.941020589825, 5902.822915312548, 5974.910376344358, 995.6365926436944, 995.915347195482, 1016.4714348538719, 1043.3218408034838, 1049.9265051446325, 22092.447688807908, 22352.770597616964, 22679.0, 22925.312240632808, 23462.64806027911, 1113.0, 1114.3815182573749, 1150.6317978247048, 1151.8790526994544, 1156.7917774800449, 11774.3524106175, 12039.277643341427, 12041.591681663702, 12195.695107622381, 12271.097035875187, 226762.0, 228026.0, 228503.0, 228752.0, 230861.0, 51522.0, 51678.22482662688, 52002.219966700526, 52112.21831672528, 54076.45923540793, 1446.3997441062052, 1500.092444071343, 1503.9624009399633, 1505.2172870107484, 1525.557588299403, 26587.867060664572, 27617.447651046463, 27677.723222767516, 27701.44597108087, 27719.584206237327, 38115.04712382213, 38195.61804381921, 38444.807775960944, 38494.0, 38555.036124097125, 203893.0, 204483.0, 205677.0, 205839.0, 206310.0, 34316.82841585776, 34674.826625866706, 35077.47383789194, 35225.82387088048, 35547.11132221715, 40657.173717014455, 42849.35725964175, 43131.137377252904, 43141.56858431501, 43636.0, 33478.83260583682, 33764.3247135051, 34555.65444345625, 34611.82694086513, 34795.65204348025, 33023.66976330302, 33294.83352583222, 33962.0, 34089.318213636085, 34368.0, 36525.45211821878, 37502.43746343862, 37664.62335376721, 37825.81087094547, 38359.61640383672, 32885.50671739974, 32911.506327404626, 33168.17079572933, 33591.0, 33777.493337599466, 23586.764132358458, 23817.64273535933, 23845.64231536563, 24078.277651670476, 24130.758692413554], "value": 17408.56077651236, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [551.5863102672995, 555.4667519181586, 563.1496440375035, 582.7028215610038, 586.2261814404984, 21496.57006859862, 22634.54730905381, 22787.886060569715, 22815.771842281592, 22888.771112288872, 24262.51474970504, 25562.233133005993, 26642.0, 26744.866275668595, 26840.19479415616, 6519.739210431589, 6661.26726060133, 6689.963055726346, 6694.062831203635, 6699.028640847074, 6596.60420374779, 6637.435817955479, 6643.568168069084, 6651.634160121192, 6662.400383965442, 6170.691465426733, 6310.968445157768, 6412.390822871843, 6426.6144031358035, 6621.337866213388, 6455.32219116993, 6538.084668146464, 6542.869142617121, 6543.443807276387, 6551.410373066423, 6078.088286757022, 6078.939210607881, 6079.939200607982, 6097.3597772233725, 6116.204893363834, 27164.1850744476, 29316.853415732785, 29696.8515157425, 29825.552616710767, 29902.70097299021, 1883.6044430669576, 1927.787943326233, 1942.5823447958614, 1944.397236856579, 1947.191915355126, 18555.536111597183, 19040.71438928417, 19077.950712710794, 19183.90408047965, 19235.13441895118, 2306.5963456395066, 2312.7571604981404, 2343.7656234376595, 2361.1027809432476, 2387.283814855554, 137565.18521851688, 137789.0, 140625.29687351498, 141160.0, 141380.29309853486, 13953.86046139546, 14456.204908729911, 14498.710025799424, 14850.777238341434, 15116.244187790566, 22118.778812211673, 22212.0, 22396.776032239468, 22603.321900343017, 22630.547389052128, 10251.436171010517, 10333.63832265882, 10364.0, 10500.0, 10569.260151789455, 9738.415695058318, 9740.220782337396, 9774.902250977542, 9860.30977831545, 9985.45080020606, 8846.424982376253, 8903.243224326005, 8999.32505062123, 9145.81708365829, 9192.724218273463, 2695.582184761365, 3375.9493607595905, 3454.844531996066, 3661.8718344857803, 3959.48526691534, 969.6896992962185, 979.7011911366959, 985.1872205430549, 994.9005099489993, 1009.6718566465928, 19588.90205548963, 19841.702374464396, 19859.702104468444, 19870.403887883385, 20226.797732022493, 1009.4347165587294, 1066.4880857188439, 1079.2768844873954, 1085.4084523934475, 1088.842117892917, 10309.15469729226, 10811.729706757395, 10908.290961087503, 10922.508487118099, 11066.667999960015, 506717.0, 510338.0, 516739.0, 518266.0, 540597.0, 122250.38874805569, 122844.0, 125536.23391298276, 125959.0, 129223.0, 1270.4473554004037, 1272.3574594829736, 1309.646395473217, 1322.841259048901, 1325.6221976736583, 19653.705194422382, 20458.181672732924, 20773.792262077186, 20911.477213069185, 21026.2640807571, 54470.0, 55327.0, 57286.0, 59298.40701592929, 59532.40467595268, 69662.0, 71309.0, 72466.27533724596, 72523.63738181275, 73344.0, 13906.791398129239, 14199.787003194753, 14344.354504047134, 14424.0, 14435.133891966516, 22533.211337603105, 22774.886125569265, 23020.539589207787, 23062.42343941415, 23310.533789324458, 18016.549586259924, 18097.185626647097, 18435.07824608791, 18567.814321856607, 18692.06539673038, 15392.846071539141, 16177.352905884103, 16520.66958660796, 17099.316027358767, 17601.031943243244, 17531.0, 17945.641087178443, 18166.182521786817, 18839.905800471457, 20165.79834201639, 14435.422583096559, 14764.704705905608, 14813.740832029398, 14817.259137042889, 14832.925835370754, 16484.505464836075, 17468.825311747227, 17550.9122454392, 17557.0, 17650.646987060445], "value": 12124.643769112196, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1357.511295933464, 1415.7168566286746, 1416.0866241274377, 1433.7132573485303, 1440.1143296872426, 13408.0, 14032.579022629337, 14056.508022219237, 14071.718565628707, 14080.577582672524, 13622.386992585332, 14009.789853152211, 14039.578812635636, 14081.788773168411, 14087.506937257172, 2585.6509371234924, 2650.986745066272, 2652.0, 2669.4260733942197, 2672.1983404978528, 2517.295157355943, 2599.363156026776, 2603.049886791317, 2610.0, 2616.1759045900576, 2521.8991240350456, 2540.16174662361, 2600.4279058607076, 2604.830686005413, 2612.4644447888136, 2544.224011676438, 2635.4729054189143, 2637.274749443899, 2642.93392665183, 2643.4845205184997, 2503.3616427810975, 2568.6403903453534, 2573.6911570611646, 2573.6911570611646, 2577.5231582157176, 13703.725925481434, 13883.4446622135, 13898.374573144132, 13918.51285205013, 13950.09324393911, 7514.323710865993, 7799.766007019798, 7849.01887264092, 7856.489328193649, 7873.5275883447175, 4822.252550854623, 4917.114919314522, 4993.725345106018, 5126.615503837227, 5129.1280482317725, 1802.9092399098488, 1824.4617837737899, 1836.4674244469088, 1903.809619038099, 1904.2763749775136, 22350.66474002891, 22440.438989025242, 22583.435414114778, 22719.43201419961, 22734.88632556843, 6214.627122372672, 6313.084602732578, 6357.682115894187, 6379.521535884827, 6532.738690452328, 15418.6145346365, 15484.148371839658, 15489.148096854557, 15499.690006199813, 15501.147436890853, 7153.785386438415, 7417.147028091731, 7426.14599321085, 7433.293837085468, 7481.438892083007, 6819.897701534481, 6835.829104272433, 6907.7236910524025, 7034.788956331318, 7068.823279418056, 4569.565891240327, 4763.666543342002, 4797.232442809134, 4850.805967761321, 4886.120498310267, 1253.8934190593902, 1260.7415479826614, 1274.7832868412393, 1300.2263653126374, 1304.210952373819, 871.2333146830748, 876.1982785751094, 898.9910100899039, 899.7660608241773, 917.206616276929, 6866.038754574363, 6972.686229119702, 7064.964675176591, 7079.539829911039, 7143.964280178566, 703.7677566403074, 708.2987842036347, 721.6788529104498, 728.7158008376738, 734.7244783206297, 4555.886102847389, 5165.948340516622, 5330.466953304638, 5352.277442545284, 5477.233187353773, 17768.200430980636, 18593.628127437376, 18629.0, 18698.252069917322, 18826.0, 17839.375621853433, 18743.437696869114, 18791.060446977597, 18934.337298194772, 19219.8078019218, 10499.842502362471, 10508.894911050944, 10637.149028077734, 10674.199435042403, 11140.832887506695, 7518.511296765692, 7878.251566101211, 7974.322182614543, 8067.475614085066, 8106.8783968241705, 15664.451744188898, 15895.920520397323, 16152.0, 16286.348546058025, 16493.67012659716, 15368.308426120628, 15656.37374505007, 16274.593135171242, 16573.41993030238, 16862.072586007398, 11184.720381990515, 11399.886001139883, 11458.71353216143, 11468.0, 11491.827622585835, 12950.481980721042, 13267.137636053625, 13419.530316438877, 13450.663733406744, 13452.0, 9963.50182490887, 10356.223283253636, 10389.584416623251, 10558.577656893945, 10590.629327973793, 9893.901060989298, 10174.186065114922, 10347.63783267582, 10363.689089327332, 10616.469176540988, 9076.818463630558, 9123.90876091257, 9147.86278205814, 9162.31282653817, 9205.76985575366, 7008.4042856355745, 7068.3285087916565, 7071.398931090709, 7108.822279443055, 7235.167955685163, 7040.119985001875, 7060.1880783708475, 7148.785536433916, 7151.2491188424465, 7245.2030276670575], "value": 6181.368687655439, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [485.5917316105938, 501.5297148966149, 513.2967834067327, 519.828456609319, 521.7730287325014, 5906.0, 6007.687040489416, 6055.666938318396, 6076.756929722806, 6160.6611636359985, 5822.835432913413, 5908.940910590893, 5932.792352267671, 6004.489618382443, 6044.486218671407, 4823.710577365369, 4876.609871210312, 4888.535589119046, 4892.412910450733, 4917.72952487613, 4796.736179510125, 4823.927641085369, 4890.633202509808, 4913.508649135094, 4916.877078073059, 4868.318435419044, 4870.878228044309, 4872.561469467747, 4879.512048795127, 4906.509349065101, 4560.954390456086, 4778.713277203379, 4784.521547845223, 4843.443004054526, 4877.170880950247, 4276.593723596254, 4319.719218250835, 4320.697551171419, 4366.868993930156, 4369.69412141147, 5836.64980101191, 5840.445157710011, 5878.941210587925, 5891.617044892111, 5906.379830117861, 5504.532114770249, 5539.279893613845, 5549.500544950974, 5580.525655319303, 5592.133219351007, 6348.968255158695, 6358.427741503287, 6398.808035758934, 6424.196975378078, 6433.871322573522, 4739.36018637483, 4747.12178247022, 4750.2874568814605, 4753.738544380058, 4756.167670657621, 6688.789381097101, 6698.966505167492, 6766.699322230465, 6773.593584384953, 6801.557898736566, 4928.876778080505, 4956.776945037482, 4960.8759781005765, 4963.801447942042, 4976.2038073908, 5019.749012549358, 5029.03287060636, 5029.899402011939, 5034.370703662043, 5065.695108725595, 5533.004059269289, 5578.525825304894, 5591.8602034948635, 5600.4959553640365, 5610.887782244332, 6371.267304259976, 6414.8717025659225, 6476.967615161988, 6510.544261901716, 6517.51118666102, 1689.341156948791, 1725.30125299254, 1726.8689008699323, 1729.0, 1745.877788554789, 1123.702218911989, 1180.2859270141612, 1196.682879037056, 1205.6684411786828, 1206.3666575047994, 5391.892162156813, 5471.0, 5483.711327837955, 5500.80747173847, 5620.156976453564, 6545.14913061299, 6589.341065893373, 6595.835104122435, 6622.503312251601, 6641.236257830315, 5673.290838645169, 5685.744141513642, 5688.943110568841, 5702.800401985911, 5707.200991861143, 3405.676460736226, 3441.7934923904145, 3472.3576138414473, 3498.7026102781037, 3517.771344862576, 6208.689565521706, 6262.2172228471445, 6286.15136956505, 6358.745650173942, 6379.585326953733, 6683.899741503881, 6689.899651505134, 6707.960266158753, 6751.426128779109, 6826.283240259801, 6327.335629758809, 6349.587276827084, 6384.297727249998, 6423.325550817097, 6435.22777266731, 6522.249941256627, 6549.181352330958, 6562.376574225441, 6596.8350791230605, 6601.174853143357, 6417.229932407954, 6481.513886458629, 6488.708008139551, 6517.283098859217, 6598.637074960923, 6227.346128656607, 6301.023341382093, 6305.337939516468, 6305.684715764101, 6362.586431881913, 4780.685311539354, 4814.494478079891, 4860.756962151949, 4870.78081486341, 4919.745464906426, 6518.902216466851, 6519.902201467076, 6549.214094308713, 6568.244651865097, 6642.767503137367, 6317.989121740499, 6344.397282258178, 6422.0, 6440.456019872218, 6456.54804163704, 6406.167198264196, 6411.839704007437, 6434.163558737334, 6444.323346048597, 6452.387023232785, 6258.780942666985, 6273.498120150286, 6325.367463253822, 6349.4920406366455, 6373.629669621054, 6253.593516421418, 6336.651484168415, 6403.839904002437, 6423.775167869102, 6444.677766111769, 5596.244506991504, 6104.4505994460715, 6113.419225173601, 6220.253569571681, 6273.177070318284], "value": 4932.407285350711, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [899.2311573604572, 913.8994710581837, 926.0230456868002, 926.3978414030879, 930.562635561286, 16672.74990875138, 16722.163891805423, 16768.580785480368, 18430.631387372243, 18950.62098758024, 19678.70481942772, 20608.587828243464, 21243.15027398902, 21367.57264854706, 21708.782912170875, 1025.7948410317947, 1059.3167407022465, 1061.257120015988, 1061.723951772537, 1061.7345663584103, 1054.1619412567027, 1065.637683187716, 1066.9306495077833, 1068.4871261794344, 1074.2856000759525, 1009.13814013146, 1042.5048102151493, 1097.4841824342545, 1104.3373975614647, 1129.8870112988718, 1080.9243352965295, 1090.5038207615555, 1091.579741799413, 1091.7379828841101, 1092.9016388525072, 787.7518581646763, 788.3771820261986, 789.124072279769, 789.7670187294761, 791.1297572670074, 19448.70826937597, 19545.21819127233, 19775.80224197754, 19813.0, 20164.092615832324, 20200.29298974529, 20458.897705511525, 20754.48113797167, 20850.47873803167, 21123.894380528152, 16173.35306587735, 16213.675726485522, 16368.427105051385, 16390.0165990041, 16470.42353517633, 3367.9831600842085, 3383.2387712764676, 3384.221629025313, 3386.255023894739, 3393.117789374756, 117501.41249293784, 118191.0, 122238.0, 123214.38392807978, 128342.99999999907, 15236.542903712907, 15966.600834978986, 16014.599635008984, 16113.194340282938, 16131.919340403223, 29525.0, 30044.54933176004, 30223.0, 30279.848600756854, 30464.086077417713, 8609.397342186112, 9029.45823250594, 9100.635974561075, 9101.81796364069, 9103.135202155785, 4218.873433796991, 4248.150369926027, 4252.787360631956, 4256.361545768098, 4266.168097221012, 5072.923906141411, 5124.975004998941, 5145.845624631267, 5148.150555158431, 5185.48145185486, 940.9294302927308, 956.8899576548647, 960.4045491794992, 966.246327864267, 981.9901800982042, 608.4706305514231, 704.5807744392079, 710.9964450177716, 727.31268950842, 732.2201855024382, 11376.317420954769, 12282.570110046105, 12884.291363975071, 12967.675808104872, 13209.141405808592, 703.9225685174627, 706.1420374245316, 708.5040471669852, 712.8788106021989, 729.8978143059976, 5499.642523235977, 5590.468905453975, 5639.859003524863, 5701.116326969326, 5770.798022069207, 98809.0, 100073.0, 103612.0, 107372.46313768381, 109869.0, 91951.0, 95657.52171239238, 95742.52128739312, 97123.51438242904, 99025.50487247662, 82350.9999999988, 82536.0, 83952.58023709842, 86114.0, 89585.0, 12299.385030748606, 12367.443465043914, 12877.935610321889, 12892.290923998895, 12952.926100809202, 35477.29045419129, 36349.00965362848, 36410.0, 36967.81516092402, 38672.41991370075, 65237.67381163064, 67349.0, 69234.30765692481, 69469.65265173641, 74237.25762742519, 18050.819491804912, 18481.0, 18651.626967460303, 18739.531511712317, 19008.904955475133, 19371.0, 19534.120964556343, 19606.90196549008, 20012.0, 20074.698879517113, 17678.911605441892, 18502.057283985527, 18887.338943136925, 19211.807881921002, 19296.710549342053, 18475.168617412517, 18590.53523661919, 19483.123259453074, 20214.0, 20323.187072516932, 18261.90869045646, 19113.139908703863, 19321.90339048296, 19622.705659415406, 19633.410997670093, 12319.938400307941, 12960.740785184054, 13054.477820887438, 13105.27920964356, 13219.933900330438, 14916.179610121113, 15042.097474151586, 15068.54794356171, 15394.15332156742, 15444.92277538605], "value": 9157.332723543543, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1538.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 110.0, 102.0, 111.0, 110.0, 110.0, 110.0, 101.0, 109.0, 110.0], "value": 107.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 120.0, 160.0, 172.0, 124.0, 157.0, 147.0, 127.0, 177.0, 129.0], "value": 145.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 163.0, 162.0, 163.0, 163.0, 163.0, 164.0, 164.0, 178.0, 165.0], "value": 164.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 76.0, 163.0, 75.0, 75.0, 75.0, 77.0, 96.0, 75.0], "value": 86.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 135.0, 83.0, 81.0, 83.0, 133.0, 84.0, 82.0, 127.0], "value": 97.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 114.0, 113.0, 113.0, 114.0, 114.0, 113.0, 114.0, 115.0], "value": 113.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 150.0, 150.0, 150.0, 150.0, 151.0, 151.0, 150.0, 150.0, 150.0], "value": 150.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 93.0, 95.0, 94.0, 94.0, 94.0, 94.0, 94.0, 93.0, 94.0], "value": 94.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 56.0, 55.0, 55.0, 58.0, 55.0, 57.0, 55.0, 54.0], "value": 55.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 54.0, 55.0, 55.0, 54.0, 55.0, 54.0, 55.0, 54.0], "value": 54.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 78.0, 79.0, 77.0, 80.0, 77.0, 80.0, 76.0, 87.0, 76.0], "value": 78.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 130.0, 131.0, 133.0, 152.0, 126.0, 133.0, 132.0, 126.0, 163.0], "value": 135.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 184.0, 183.0, 188.0, 180.0, 204.0, 185.0, 186.0, 185.0, 179.0], "value": 185.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 73.0, 68.0, 69.0, 69.0, 68.0, 68.0, 73.0, 67.0, 69.0], "value": 69.1, "unit": "score"}]}]} 18:32:50 INFO - Return code: 0 18:32:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:32:50 INFO - # TBPL SUCCESS # 18:32:50 INFO - Running post-action listener: _package_coverage_data 18:32:50 INFO - Running post-action listener: _resource_record_post_action 18:32:50 INFO - [mozharness: 2017-07-18 01:32:50.099573Z] Finished run-tests step (success) 18:32:50 INFO - Running post-run listener: _resource_record_post_run 18:32:50 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 8192; Write bytes: 449073152; Read time: 16; Write time: 307496 18:32:50 INFO - TinderboxPrint: CPU usage
13.1% 18:32:50 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 16 18:32:50 INFO - TinderboxPrint: I/O write bytes / time
449,073,152 / 307,496 18:32:50 INFO - TinderboxPrint: CPU idle
8,254.3 (86.9%) 18:32:50 INFO - TinderboxPrint: CPU user
1,195.5 (12.6%) 18:32:50 INFO - TinderboxPrint: Swap in / out
0 / 0 18:32:50 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 121016320; Read time: 0; Write time: 93240 18:32:50 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 18:32:50 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 4096; Write bytes: 328056832; Read time: 8; Write time: 214256 18:32:50 INFO - Running post-run listener: _upload_blobber_files 18:32:50 INFO - Blob upload gear active. 18:32:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:32:50 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 18:32:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:32:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:32:51 INFO - Traceback (most recent call last): 18:32:51 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 253, in 18:32:51 INFO - main() 18:32:51 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 235, in main 18:32:51 INFO - filetype_whitelist = get_server_whitelist(args['--url']) 18:32:51 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 69, in get_server_whitelist 18:32:51 INFO - return set(response.json().get('whitelist', [])) 18:32:51 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/requests/models.py", line 651, in json 18:32:51 INFO - return json.loads(self.text or self.content, **kwargs) 18:32:51 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/simplejson/__init__.py", line 488, in loads 18:32:51 INFO - return _default_decoder.decode(s) 18:32:51 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/simplejson/decoder.py", line 370, in decode 18:32:51 INFO - obj, end = self.raw_decode(s) 18:32:51 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/simplejson/decoder.py", line 389, in raw_decode 18:32:51 INFO - return self.scan_once(s, idx=_w(s, idx).end()) 18:32:51 INFO - simplejson.scanner.JSONDecodeError: Expecting value: line 1 column 1 (char 0) 18:32:51 ERROR - Return code: 1 18:32:51 INFO - Setting buildbot property blobber_files to {} 18:32:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:32:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:32:51 INFO - Contents: 18:32:51 INFO - blobber_files:{} 18:32:51 INFO - Running post-run listener: copy_logs_to_upload_dir 18:32:51 INFO - Copying logs to upload dir... 18:32:51 INFO - mkdir: /builds/slave/test/build/upload/logs 18:32:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.268865 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2017-07-17 18:32:51.047024) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-17 18:32:51.050220) ========= 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=03627c79f6bb3d1578c4a402000001ba-1500340271.561761-941163189 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{} build_url:https://queue.taskcluster.net/v1/task/H7H9rhlpTy6vV-5GODkT0Q/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011409 build_url: 'https://queue.taskcluster.net/v1/task/H7H9rhlpTy6vV-5GODkT0Q/artifacts/public/build/target.tar.bz2' blobber_files: '{}' ========= master_lag: 0.04 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-17 18:32:51.104038) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-17 18:32:51.104396) ========= 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=03627c79f6bb3d1578c4a402000001ba-1500340271.561761-941163189 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004627 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-17 18:32:51.156880) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-17 18:32:51.157224) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-17 18:32:51.157534) ========= ========= Total master_lag: 0.28 =========