builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-002 starttime: 1494518303.93 results: success (0) revision: 3b96f277325747fe668ca8cd896d2f581238e4ee ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-11 08:58:23.929289) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-11 08:58:23.929743) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-11 08:58:23.930024) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-11 08:58:23.944515) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 08:58:23.944816) ========= 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=a0d2d2de893abaa8702a9954000001a7-1494518303.374353-1973210711 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-05-11 08:58:24-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 21.4M=0.001s 2017-05-11 08:58:24 (21.4 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.130828 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 08:58:24.089239) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 08:58:24.089572) ========= 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=a0d2d2de893abaa8702a9954000001a7-1494518303.374353-1973210711 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021420 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 08:58:24.141042) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-11 08:58:24.141461) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3b96f277325747fe668ca8cd896d2f581238e4ee --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 3b96f277325747fe668ca8cd896d2f581238e4ee --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=a0d2d2de893abaa8702a9954000001a7-1494518303.374353-1973210711 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-05-11 08:58:24,520 truncating revision to first 12 chars 2017-05-11 08:58:24,520 Setting DEBUG logging. 2017-05-11 08:58:24,520 attempt 1/10 2017-05-11 08:58:24,520 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3b96f2773257?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-11 08:58:25,456 unpacking tar archive at: mozilla-central-3b96f2773257/testing/mozharness/ program finished with exit code 0 elapsedTime=1.288773 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-11 08:58:25.443564) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-11 08:58:25.443889) ========= script_repo_revision: 3b96f277325747fe668ca8cd896d2f581238e4ee ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-11 08:58:25.444261) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-11 08:58:25.444549) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-11 08:58:25.453760) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 37 mins, 43 secs) (at 2017-05-11 08:58:25.454027) ========= /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=a0d2d2de893abaa8702a9954000001a7-1494518303.374353-1973210711 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:58:25 INFO - MultiFileLogger online at 20170511 08:58:25 in /builds/slave/test 08:58:25 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 08:58:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:58:25 INFO - {'append_to_log': False, 08:58:25 INFO - 'base_work_dir': '/builds/slave/test', 08:58:25 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 08:58:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:58:25 INFO - 'branch': 'Firefox-Non-PGO', 08:58:25 INFO - 'buildbot_json_path': 'buildprops.json', 08:58:25 INFO - 'config_files': ('talos/linux_config.py',), 08:58:25 INFO - 'default_actions': ('clobber', 08:58:25 INFO - 'read-buildbot-config', 08:58:25 INFO - 'download-and-extract', 08:58:25 INFO - 'populate-webroot', 08:58:25 INFO - 'create-virtualenv', 08:58:25 INFO - 'install', 08:58:25 INFO - 'run-tests'), 08:58:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:58:25 INFO - 'download_minidump_stackwalk': True, 08:58:25 INFO - 'download_symbols': 'ondemand', 08:58:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:58:25 INFO - 'tooltool.py': '/tools/tooltool.py', 08:58:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:58:25 INFO - '/tools/misc-python/virtualenv.py')}, 08:58:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:58:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:58:25 INFO - 'gecko_profile': False, 08:58:25 INFO - 'gecko_profile_interval': 0, 08:58:25 INFO - 'installer_path': 'installer.exe', 08:58:25 INFO - 'log_level': 'info', 08:58:25 INFO - 'log_name': 'talos', 08:58:25 INFO - 'log_to_console': True, 08:58:25 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:58:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:58:25 INFO - 'opt_config_files': (), 08:58:25 INFO - 'pip_index': False, 08:58:25 INFO - 'suite': 'dromaeojs-e10s', 08:58:25 INFO - 'system_bits': '32', 08:58:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:58:25 INFO - 'title': 'talos-linux64-ix-002', 08:58:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:58:25 INFO - 'use_talos_json': True, 08:58:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:58:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:58:25 INFO - 'work_dir': 'build'} 08:58:25 INFO - [mozharness: 2017-05-11 15:58:25.928867Z] Running clobber step. 08:58:25 INFO - Running pre-action listener: _resource_record_pre_action 08:58:25 INFO - Running main action method: clobber 08:58:25 INFO - rmtree: /builds/slave/test/build 08:58:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:58:26 INFO - Running post-action listener: _resource_record_post_action 08:58:26 INFO - [mozharness: 2017-05-11 15:58:26.915355Z] Finished clobber step (success) 08:58:26 INFO - [mozharness: 2017-05-11 15:58:26.915456Z] Running read-buildbot-config step. 08:58:26 INFO - Running pre-action listener: _resource_record_pre_action 08:58:26 INFO - Running main action method: read_buildbot_config 08:58:26 INFO - Using buildbot properties: 08:58:26 INFO - { 08:58:26 INFO - "project": "", 08:58:26 INFO - "product": "firefox", 08:58:26 INFO - "who": "ffxbld@noreply.mozilla.org", 08:58:26 INFO - "installer_path": "public/build/target.tar.bz2", 08:58:26 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 08:58:26 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 08:58:26 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 08:58:26 INFO - "stage_platform": "linux64", 08:58:26 INFO - "basedir": "/builds/slave/test", 08:58:26 INFO - "buildnumber": 869, 08:58:26 INFO - "platform": "ubuntu64_hw", 08:58:26 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 08:58:26 INFO - "slavebuilddir": "test", 08:58:26 INFO - "taskId": "Q9QPSNOgQaGnfZMlxjtVNQ", 08:58:26 INFO - "branch": "mozilla-central", 08:58:26 INFO - "script_repo_revision": "production", 08:58:26 INFO - "revision": "3b96f277325747fe668ca8cd896d2f581238e4ee", 08:58:26 INFO - "slavename": "talos-linux64-ix-002", 08:58:26 INFO - "repo_path": "mozilla-central" 08:58:26 INFO - } 08:58:26 INFO - Finding installer, test and symbols from parent task. 08:58:26 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q9QPSNOgQaGnfZMlxjtVNQ'}, attempt #1 08:58:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q9QPSNOgQaGnfZMlxjtVNQ'}, attempt #1 08:58:27 INFO - Task dependencies: VqHjFaJWSBWRUdUhiRa35Q 08:58:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VqHjFaJWSBWRUdUhiRa35Q'}, attempt #1 08:58:28 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/VqHjFaJWSBWRUdUhiRa35Q/artifacts/public/build/target.tar.bz2 08:58:28 INFO - Set test_url: https://queue.taskcluster.net/v1/task/VqHjFaJWSBWRUdUhiRa35Q/artifacts/public/build/test_packages.json 08:58:28 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/VqHjFaJWSBWRUdUhiRa35Q/artifacts/public/build/target.crashreporter-symbols.zip 08:58:28 INFO - Running post-action listener: _resource_record_post_action 08:58:28 INFO - [mozharness: 2017-05-11 15:58:28.317038Z] Finished read-buildbot-config step (success) 08:58:28 INFO - [mozharness: 2017-05-11 15:58:28.317255Z] Running download-and-extract step. 08:58:28 INFO - Running pre-action listener: _resource_record_pre_action 08:58:28 INFO - Running main action method: download_and_extract 08:58:28 INFO - mkdir: /builds/slave/test/build/tests 08:58:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:58:28 INFO - trying https://queue.taskcluster.net/v1/task/VqHjFaJWSBWRUdUhiRa35Q/artifacts/public/build/target.test_packages.json 08:58:28 INFO - Downloading https://queue.taskcluster.net/v1/task/VqHjFaJWSBWRUdUhiRa35Q/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 08:58:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VqHjFaJWSBWRUdUhiRa35Q/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 08:58:28 INFO - Downloaded 986 bytes. 08:58:28 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 08:58:28 INFO - Using the following test package requirements: 08:58:28 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 08:58:28 INFO - u'common': [u'target.common.tests.zip'], 08:58:28 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 08:58:28 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 08:58:28 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 08:58:28 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 08:58:28 INFO - u'mozbase': [u'target.common.tests.zip'], 08:58:28 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 08:58:28 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 08:58:28 INFO - u'web-platform': [u'target.common.tests.zip', 08:58:28 INFO - u'target.web-platform.tests.zip'], 08:58:28 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 08:58:28 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 08:58:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VqHjFaJWSBWRUdUhiRa35Q/artifacts/public/build/target.common.tests.zip 08:58:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VqHjFaJWSBWRUdUhiRa35Q/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:58:28 INFO - Fetch https://queue.taskcluster.net/v1/task/VqHjFaJWSBWRUdUhiRa35Q/artifacts/public/build/target.common.tests.zip into memory 08:58:30 INFO - Content-Length response header: 58587689 08:58:30 INFO - Bytes received: 58587689 08:58:39 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 08:58:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VqHjFaJWSBWRUdUhiRa35Q/artifacts/public/build/target.common.tests.zip 08:58:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VqHjFaJWSBWRUdUhiRa35Q/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:58:39 INFO - Fetch https://queue.taskcluster.net/v1/task/VqHjFaJWSBWRUdUhiRa35Q/artifacts/public/build/target.common.tests.zip into memory 08:58:40 INFO - Content-Length response header: 58587689 08:58:40 INFO - Bytes received: 58587689 08:58:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VqHjFaJWSBWRUdUhiRa35Q/artifacts/public/build/target.talos.tests.zip 08:58:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VqHjFaJWSBWRUdUhiRa35Q/artifacts/public/build/target.talos.tests.zip'}, attempt #1 08:58:51 INFO - Fetch https://queue.taskcluster.net/v1/task/VqHjFaJWSBWRUdUhiRa35Q/artifacts/public/build/target.talos.tests.zip into memory 08:58:52 INFO - Content-Length response header: 14409411 08:58:52 INFO - Bytes received: 14409411 08:58:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:58:52 INFO - trying https://queue.taskcluster.net/v1/task/VqHjFaJWSBWRUdUhiRa35Q/artifacts/public/build/target.tar.bz2 08:58:52 INFO - Downloading https://queue.taskcluster.net/v1/task/VqHjFaJWSBWRUdUhiRa35Q/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 08:58:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VqHjFaJWSBWRUdUhiRa35Q/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 08:58:53 INFO - Downloaded 63013131 bytes. 08:58:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VqHjFaJWSBWRUdUhiRa35Q/artifacts/public/build/target.tar.bz2 08:58:53 INFO - mkdir: /builds/slave/test/properties 08:58:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:58:53 INFO - Writing to file /builds/slave/test/properties/build_url 08:58:53 INFO - Contents: 08:58:53 INFO - build_url:https://queue.taskcluster.net/v1/task/VqHjFaJWSBWRUdUhiRa35Q/artifacts/public/build/target.tar.bz2 08:58:53 INFO - Running post-action listener: _resource_record_post_action 08:58:53 INFO - Running post-action listener: set_extra_try_arguments 08:58:53 INFO - [mozharness: 2017-05-11 15:58:53.933304Z] Finished download-and-extract step (success) 08:58:53 INFO - [mozharness: 2017-05-11 15:58:53.933413Z] Running populate-webroot step. 08:58:53 INFO - Running pre-action listener: _resource_record_pre_action 08:58:53 INFO - Running main action method: populate_webroot 08:58:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:58:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:58:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:58:53 INFO - '--tppagecycles', 08:58:53 INFO - '7'], 08:58:53 INFO - 'tests': ['tsvgm']}}, 08:58:53 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:58:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:58:53 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:58:53 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:58:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:58:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:58:53 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:58:53 INFO - 'talos_options': ['--disable-e10s'], 08:58:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:58:53 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:58:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:58:53 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:58:53 INFO - 'talos_options': ['--disable-e10s'], 08:58:53 INFO - 'tests': ['damp', 'tps']}, 08:58:53 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:58:53 INFO - 'tests': ['damp', 'tps']}, 08:58:53 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:58:53 INFO - 'tests': ['dromaeo_dom']}, 08:58:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:58:53 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:58:53 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 08:58:53 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 08:58:53 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:58:53 INFO - 'tests': ['a11yr', 08:58:53 INFO - 'ts_paint', 08:58:53 INFO - 'tpaint', 08:58:53 INFO - 'sessionrestore', 08:58:53 INFO - 'sessionrestore_no_auto_restore', 08:58:53 INFO - 'tabpaint']}, 08:58:53 INFO - 'other-e10s': {'tests': ['a11yr', 08:58:53 INFO - 'ts_paint', 08:58:53 INFO - 'tpaint', 08:58:53 INFO - 'sessionrestore', 08:58:53 INFO - 'sessionrestore_no_auto_restore', 08:58:53 INFO - 'tabpaint']}, 08:58:53 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:58:53 INFO - 'ts_paint', 08:58:53 INFO - 'tpaint', 08:58:53 INFO - 'sessionrestore', 08:58:53 INFO - 'sessionrestore_no_auto_restore', 08:58:53 INFO - 'tabpaint']}, 08:58:53 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:58:53 INFO - 'ts_paint', 08:58:53 INFO - 'tpaint', 08:58:53 INFO - 'sessionrestore', 08:58:53 INFO - 'sessionrestore_no_auto_restore', 08:58:53 INFO - 'tabpaint']}, 08:58:53 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:58:53 INFO - 'tests': ['a11yr', 08:58:53 INFO - 'ts_paint', 08:58:53 INFO - 'tpaint', 08:58:53 INFO - 'sessionrestore', 08:58:53 INFO - 'sessionrestore_no_auto_restore', 08:58:53 INFO - 'tabpaint']}, 08:58:53 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:58:53 INFO - 'tests': ['a11yr', 08:58:53 INFO - 'ts_paint', 08:58:53 INFO - 'tpaint', 08:58:53 INFO - 'sessionrestore', 08:58:53 INFO - 'sessionrestore_no_auto_restore', 08:58:53 INFO - 'tabpaint']}, 08:58:53 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 08:58:53 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 08:58:53 INFO - 'bloom_basic_ref']}, 08:58:53 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:58:53 INFO - 'tests': ['tsvgx', 08:58:53 INFO - 'tsvgr_opacity', 08:58:53 INFO - 'tart', 08:58:53 INFO - 'tscrollx', 08:58:53 INFO - 'cart', 08:58:53 INFO - 'tsvg_static']}, 08:58:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:58:53 INFO - 'tsvgr_opacity', 08:58:53 INFO - 'tart', 08:58:53 INFO - 'tscrollx', 08:58:53 INFO - 'cart', 08:58:53 INFO - 'tsvg_static']}, 08:58:53 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:58:53 INFO - 'talos_options': ['--disable-e10s'], 08:58:53 INFO - 'tests': ['tp5o']}, 08:58:53 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:58:53 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:58:53 INFO - 'talos_options': ['--disable-e10s', 08:58:53 INFO - '--xperf_path', 08:58:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:58:53 INFO - 'tests': ['tp5n']}, 08:58:53 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:58:53 INFO - 'talos_options': ['--xperf_path', 08:58:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:58:53 INFO - 'tests': ['tp5n']}}, 08:58:53 INFO - 'talos.zip': {'path': '', 08:58:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:58:53 INFO - Running post-action listener: _resource_record_post_action 08:58:53 INFO - [mozharness: 2017-05-11 15:58:53.946988Z] Finished populate-webroot step (success) 08:58:53 INFO - [mozharness: 2017-05-11 15:58:53.947076Z] Running create-virtualenv step. 08:58:53 INFO - Running pre-action listener: _resource_record_pre_action 08:58:53 INFO - Running main action method: create_virtualenv 08:58:53 INFO - Creating virtualenv /builds/slave/test/build/venv 08:58:53 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 08:58:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:58:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:58:54 INFO - Using real prefix '/usr' 08:58:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:58:54 INFO - Installing distribute.............................................................................................................................................................................................done. 08:58:56 INFO - Installing pip.................done. 08:58:56 INFO - Return code: 0 08:58:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:58:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:58:56 INFO - Reading from file tmpfile_stdout 08:58:56 INFO - Output received: 08:58:56 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) 08:58:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:58:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:58:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:58:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:58:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:58:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13d11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x159da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1743770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1740a30>, '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 0x17417f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1741ca0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1494518303.374353-1973210711', '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 08:58:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:58:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:58:56 INFO - Using env: {'DISPLAY': ':0', 08:58:56 INFO - 'HOME': '/home/cltbld', 08:58:56 INFO - 'LANG': 'en_US.UTF-8', 08:58:56 INFO - 'LANGUAGE': 'en_US:en', 08:58:56 INFO - 'LOGNAME': 'cltbld', 08:58:56 INFO - 'MAIL': '/var/mail/cltbld', 08:58:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:58:56 INFO - 'MOZ_NO_REMOTE': '1', 08:58:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:58:56 INFO - 'NO_EM_RESTART': '1', 08:58:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:58:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:58:56 INFO - 'PWD': '/builds/slave/test', 08:58:56 INFO - 'SHELL': '/bin/bash', 08:58:56 INFO - 'SHLVL': '1', 08:58:56 INFO - 'TERM': 'linux', 08:58:56 INFO - 'TMOUT': '86400', 08:58:56 INFO - 'USER': 'cltbld', 08:58:56 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1494518303.374353-1973210711', 08:58:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:58:56 INFO - '_': '/tools/buildbot/bin/python'} 08:58:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:58:56 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 08:58:56 INFO - Cleaning up... 08:58:56 INFO - Return code: 0 08:58:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:58:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:58:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:58:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:58:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:58:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13d11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x159da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1743770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1740a30>, '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 0x17417f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1741ca0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1494518303.374353-1973210711', '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 08:58:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:58:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:58:56 INFO - Using env: {'DISPLAY': ':0', 08:58:56 INFO - 'HOME': '/home/cltbld', 08:58:56 INFO - 'LANG': 'en_US.UTF-8', 08:58:56 INFO - 'LANGUAGE': 'en_US:en', 08:58:56 INFO - 'LOGNAME': 'cltbld', 08:58:56 INFO - 'MAIL': '/var/mail/cltbld', 08:58:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:58:56 INFO - 'MOZ_NO_REMOTE': '1', 08:58:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:58:56 INFO - 'NO_EM_RESTART': '1', 08:58:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:58:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:58:56 INFO - 'PWD': '/builds/slave/test', 08:58:56 INFO - 'SHELL': '/bin/bash', 08:58:56 INFO - 'SHLVL': '1', 08:58:56 INFO - 'TERM': 'linux', 08:58:56 INFO - 'TMOUT': '86400', 08:58:56 INFO - 'USER': 'cltbld', 08:58:56 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1494518303.374353-1973210711', 08:58:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:58:56 INFO - '_': '/tools/buildbot/bin/python'} 08:58:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:58:57 INFO - Downloading/unpacking psutil>=3.1.1 08:58: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 08:58: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 08:58:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:58:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:58: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 08:58: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 09:01:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:01:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:01:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:01:04 INFO - Installing collected packages: psutil 09:01:04 INFO - Running setup.py install for psutil 09:01:04 INFO - building 'psutil._psutil_linux' extension 09:01:04 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 09:01:04 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 09:01:04 INFO - building 'psutil._psutil_posix' extension 09:01:04 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 09:01:05 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 09:01:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:01:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:01:05 INFO - Successfully installed psutil 09:01:05 INFO - Cleaning up... 09:01:05 INFO - Return code: 0 09:01:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:01:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13d11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x159da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1743770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1740a30>, '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 0x17417f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1741ca0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1494518303.374353-1973210711', '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 09:01:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:01:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:01:05 INFO - Using env: {'DISPLAY': ':0', 09:01:05 INFO - 'HOME': '/home/cltbld', 09:01:05 INFO - 'LANG': 'en_US.UTF-8', 09:01:05 INFO - 'LANGUAGE': 'en_US:en', 09:01:05 INFO - 'LOGNAME': 'cltbld', 09:01:05 INFO - 'MAIL': '/var/mail/cltbld', 09:01:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:05 INFO - 'MOZ_NO_REMOTE': '1', 09:01:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:01:05 INFO - 'NO_EM_RESTART': '1', 09:01:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:01:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:05 INFO - 'PWD': '/builds/slave/test', 09:01:05 INFO - 'SHELL': '/bin/bash', 09:01:05 INFO - 'SHLVL': '1', 09:01:05 INFO - 'TERM': 'linux', 09:01:05 INFO - 'TMOUT': '86400', 09:01:05 INFO - 'USER': 'cltbld', 09:01:05 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1494518303.374353-1973210711', 09:01:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:05 INFO - '_': '/tools/buildbot/bin/python'} 09:01:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:01:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:03:12 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:03:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:03:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:03:12 INFO - Installing collected packages: mozsystemmonitor 09:03:12 INFO - Running setup.py install for mozsystemmonitor 09:03:12 INFO - Successfully installed mozsystemmonitor 09:03:12 INFO - Cleaning up... 09:03:12 INFO - Return code: 0 09:03:12 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:03:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:03:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:03:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:03:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:03:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13d11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x159da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1743770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1740a30>, '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 0x17417f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1741ca0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1494518303.374353-1973210711', '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 09:03:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:03:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:03:13 INFO - Using env: {'DISPLAY': ':0', 09:03:13 INFO - 'HOME': '/home/cltbld', 09:03:13 INFO - 'LANG': 'en_US.UTF-8', 09:03:13 INFO - 'LANGUAGE': 'en_US:en', 09:03:13 INFO - 'LOGNAME': 'cltbld', 09:03:13 INFO - 'MAIL': '/var/mail/cltbld', 09:03:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:13 INFO - 'MOZ_NO_REMOTE': '1', 09:03:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:03:13 INFO - 'NO_EM_RESTART': '1', 09:03:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:03:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:13 INFO - 'PWD': '/builds/slave/test', 09:03:13 INFO - 'SHELL': '/bin/bash', 09:03:13 INFO - 'SHLVL': '1', 09:03:13 INFO - 'TERM': 'linux', 09:03:13 INFO - 'TMOUT': '86400', 09:03:13 INFO - 'USER': 'cltbld', 09:03:13 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1494518303.374353-1973210711', 09:03:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:13 INFO - '_': '/tools/buildbot/bin/python'} 09:03:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:03:13 INFO - Downloading/unpacking jsonschema==2.5.1 09:03:13 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 09:03:13 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 09:03:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:03:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:03:13 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 09:03:13 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 09:05:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:05:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:05:20 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 09:05:20 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 09:05:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:05:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:05:20 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 09:05:20 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 09:07:26 INFO - Downloading functools32-3.2.3-2.tar.gz 09:07:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:07:26 INFO - warning: no files found matching '*.txt' 09:07:26 INFO - no previously-included directories found matching 'build' 09:07:26 INFO - no previously-included directories found matching 'dist' 09:07:26 INFO - no previously-included directories found matching '.git*' 09:07:26 INFO - Installing collected packages: jsonschema, functools32 09:07:26 INFO - Running setup.py install for functools32 09:07:27 INFO - warning: no files found matching '*.txt' 09:07:27 INFO - no previously-included directories found matching 'build' 09:07:27 INFO - no previously-included directories found matching 'dist' 09:07:27 INFO - no previously-included directories found matching '.git*' 09:07:27 INFO - Successfully installed jsonschema functools32 09:07:27 INFO - Cleaning up... 09:07:27 INFO - Return code: 0 09:07:27 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:07:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13d11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x159da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1743770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1740a30>, '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 0x17417f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1741ca0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1494518303.374353-1973210711', '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 09:07:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:07:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:07:27 INFO - Using env: {'DISPLAY': ':0', 09:07:27 INFO - 'HOME': '/home/cltbld', 09:07:27 INFO - 'LANG': 'en_US.UTF-8', 09:07:27 INFO - 'LANGUAGE': 'en_US:en', 09:07:27 INFO - 'LOGNAME': 'cltbld', 09:07:27 INFO - 'MAIL': '/var/mail/cltbld', 09:07:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:27 INFO - 'MOZ_NO_REMOTE': '1', 09:07:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:07:27 INFO - 'NO_EM_RESTART': '1', 09:07:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:07:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:27 INFO - 'PWD': '/builds/slave/test', 09:07:27 INFO - 'SHELL': '/bin/bash', 09:07:27 INFO - 'SHLVL': '1', 09:07:27 INFO - 'TERM': 'linux', 09:07:27 INFO - 'TMOUT': '86400', 09:07:27 INFO - 'USER': 'cltbld', 09:07:27 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1494518303.374353-1973210711', 09:07:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:27 INFO - '_': '/tools/buildbot/bin/python'} 09:07:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:27 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:07:27 INFO - Cleaning up... 09:07:27 INFO - Return code: 0 09:07:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:07:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13d11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x159da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1743770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1740a30>, '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 0x17417f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1741ca0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1494518303.374353-1973210711', '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 09:07:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:07:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:07:27 INFO - Using env: {'DISPLAY': ':0', 09:07:27 INFO - 'HOME': '/home/cltbld', 09:07:27 INFO - 'LANG': 'en_US.UTF-8', 09:07:27 INFO - 'LANGUAGE': 'en_US:en', 09:07:27 INFO - 'LOGNAME': 'cltbld', 09:07:27 INFO - 'MAIL': '/var/mail/cltbld', 09:07:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:27 INFO - 'MOZ_NO_REMOTE': '1', 09:07:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:07:27 INFO - 'NO_EM_RESTART': '1', 09:07:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:07:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:27 INFO - 'PWD': '/builds/slave/test', 09:07:27 INFO - 'SHELL': '/bin/bash', 09:07:27 INFO - 'SHLVL': '1', 09:07:27 INFO - 'TERM': 'linux', 09:07:27 INFO - 'TMOUT': '86400', 09:07:27 INFO - 'USER': 'cltbld', 09:07:27 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1494518303.374353-1973210711', 09:07:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:27 INFO - '_': '/tools/buildbot/bin/python'} 09:07:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:27 INFO - Downloading/unpacking blobuploader==1.2.4 09:07:27 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 09:07:27 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 09:07:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:07:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:07:27 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 09:07:27 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 09:09:34 INFO - Downloading blobuploader-1.2.4.tar.gz 09:09:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:09:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:09:34 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 09:09:34 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 09:09:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:34 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 09:09:34 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 09:11:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:11:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:11: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 09:11: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 09:11:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11: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 09:11: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 09:13:47 INFO - Downloading docopt-0.6.1.tar.gz 09:13:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:13:47 INFO - Installing collected packages: blobuploader, requests, docopt 09:13:47 INFO - Running setup.py install for blobuploader 09:13:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:13:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:13:48 INFO - Running setup.py install for requests 09:13:48 INFO - Running setup.py install for docopt 09:13:48 INFO - Successfully installed blobuploader requests docopt 09:13:48 INFO - Cleaning up... 09:13:48 INFO - Return code: 0 09:13:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:13:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:13:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:13:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13d11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x159da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1743770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1740a30>, '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 0x17417f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1741ca0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1494518303.374353-1973210711', '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 09:13:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:13:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:13:48 INFO - Using env: {'DISPLAY': ':0', 09:13:48 INFO - 'HOME': '/home/cltbld', 09:13:48 INFO - 'LANG': 'en_US.UTF-8', 09:13:48 INFO - 'LANGUAGE': 'en_US:en', 09:13:48 INFO - 'LOGNAME': 'cltbld', 09:13:48 INFO - 'MAIL': '/var/mail/cltbld', 09:13:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:48 INFO - 'MOZ_NO_REMOTE': '1', 09:13:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:13:48 INFO - 'NO_EM_RESTART': '1', 09:13:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:13:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:48 INFO - 'PWD': '/builds/slave/test', 09:13:48 INFO - 'SHELL': '/bin/bash', 09:13:48 INFO - 'SHLVL': '1', 09:13:48 INFO - 'TERM': 'linux', 09:13:48 INFO - 'TMOUT': '86400', 09:13:48 INFO - 'USER': 'cltbld', 09:13:48 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1494518303.374353-1973210711', 09:13:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:48 INFO - '_': '/tools/buildbot/bin/python'} 09:13:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:13:48 INFO - Running setup.py (path:/tmp/pip-fAcYKF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:13:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:13:48 INFO - Running setup.py (path:/tmp/pip-YumNYP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:13:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:13:48 INFO - Running setup.py (path:/tmp/pip-DnWxFF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:13:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:13:49 INFO - Running setup.py (path:/tmp/pip-buxGEL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:13:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:13:49 INFO - Running setup.py (path:/tmp/pip-lrWbNU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:13:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:13:49 INFO - Running setup.py (path:/tmp/pip-XruRaM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:13:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:13:49 INFO - Running setup.py (path:/tmp/pip-QKfIns-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:13:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:13:49 INFO - Running setup.py (path:/tmp/pip-SbHOg6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:13:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:13:49 INFO - Running setup.py (path:/tmp/pip-IbZ8pS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:13:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:13:49 INFO - Running setup.py (path:/tmp/pip-aR96we-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:13:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:13:49 INFO - Running setup.py (path:/tmp/pip-dkyKzP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:13:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:13:49 INFO - Running setup.py (path:/tmp/pip-qlT4yt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:13:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:13:49 INFO - Running setup.py (path:/tmp/pip-qzGYhf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:13:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:13:49 INFO - Running setup.py (path:/tmp/pip-pUHwb7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:13:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:13:49 INFO - Running setup.py (path:/tmp/pip-o5oDUB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:13:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:13:49 INFO - Running setup.py (path:/tmp/pip-YVH3pV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:13:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:13:49 INFO - Running setup.py (path:/tmp/pip-wZYRaI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:13:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:13:50 INFO - Running setup.py install for manifestparser 09:13:50 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:13:50 INFO - Running setup.py install for mozcrash 09:13:50 INFO - Running setup.py install for mozdebug 09:13:50 INFO - Running setup.py install for mozdevice 09:13:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:13:50 INFO - Running setup.py install for mozfile 09:13:50 INFO - Running setup.py install for mozhttpd 09:13:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:13:50 INFO - Running setup.py install for mozinfo 09:13:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:13:50 INFO - Running setup.py install for mozInstall 09:13:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:13:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:13:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:13:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:13:50 INFO - Running setup.py install for mozleak 09:13:50 INFO - Running setup.py install for mozlog 09:13:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:13:51 INFO - Running setup.py install for moznetwork 09:13:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:13:51 INFO - Running setup.py install for mozprocess 09:13:51 INFO - Running setup.py install for mozprofile 09:13:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:13:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:13:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:13:51 INFO - Running setup.py install for mozrunner 09:13:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:13:51 INFO - Running setup.py install for mozscreenshot 09:13:51 INFO - Running setup.py install for moztest 09:13:51 INFO - Running setup.py install for mozversion 09:13:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:13:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:13:51 INFO - Cleaning up... 09:13:51 INFO - Return code: 0 09:13:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:13:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:13:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:13:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13d11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x159da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1743770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1740a30>, '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 0x17417f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1741ca0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1494518303.374353-1973210711', '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 09:13:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:13:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:13:51 INFO - Using env: {'DISPLAY': ':0', 09:13:51 INFO - 'HOME': '/home/cltbld', 09:13:51 INFO - 'LANG': 'en_US.UTF-8', 09:13:51 INFO - 'LANGUAGE': 'en_US:en', 09:13:51 INFO - 'LOGNAME': 'cltbld', 09:13:51 INFO - 'MAIL': '/var/mail/cltbld', 09:13:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:51 INFO - 'MOZ_NO_REMOTE': '1', 09:13:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:13:51 INFO - 'NO_EM_RESTART': '1', 09:13:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:13:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:51 INFO - 'PWD': '/builds/slave/test', 09:13:51 INFO - 'SHELL': '/bin/bash', 09:13:51 INFO - 'SHLVL': '1', 09:13:51 INFO - 'TERM': 'linux', 09:13:51 INFO - 'TMOUT': '86400', 09:13:51 INFO - 'USER': 'cltbld', 09:13:51 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1494518303.374353-1973210711', 09:13:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:51 INFO - '_': '/tools/buildbot/bin/python'} 09:13:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:13:52 INFO - Running setup.py (path:/tmp/pip-tuaU6w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:13: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)) 09:13:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:13:52 INFO - Running setup.py (path:/tmp/pip-ZW_1L9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:13:52 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)) 09:13:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:13:52 INFO - Running setup.py (path:/tmp/pip-9_Dn9l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:13:52 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)) 09:13:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:13:52 INFO - Running setup.py (path:/tmp/pip-BsWOtb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:13:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:13:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:13:52 INFO - Running setup.py (path:/tmp/pip-Xf43iC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:13:52 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)) 09:13:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:13:52 INFO - Running setup.py (path:/tmp/pip-dkLjPD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:13:52 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)) 09:13:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:13:52 INFO - Running setup.py (path:/tmp/pip-PpKpku-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:13:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:13:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:13:52 INFO - Running setup.py (path:/tmp/pip-VxP_Id-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:13:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:13:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:13:52 INFO - Running setup.py (path:/tmp/pip-xfefuk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:13:52 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)) 09:13:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:13:52 INFO - Running setup.py (path:/tmp/pip-Pir3ID-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:13:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:13:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:13:52 INFO - Running setup.py (path:/tmp/pip-Zlshqn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:13:52 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)) 09:13:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:13:52 INFO - Running setup.py (path:/tmp/pip-ZMs0VK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:13:52 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)) 09:13:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:13:52 INFO - Running setup.py (path:/tmp/pip-m4VSDJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:13:52 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)) 09:13:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:13:53 INFO - Running setup.py (path:/tmp/pip-ZaQTuW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:13: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)) 09:13:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:13:53 INFO - Running setup.py (path:/tmp/pip-5BzUqi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:13: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)) 09:13:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:13:53 INFO - Running setup.py (path:/tmp/pip-zy6jAq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:13:53 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)) 09:13:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:13:53 INFO - Running setup.py (path:/tmp/pip-DX1RsX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:13:53 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)) 09:13:53 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)) 09:13:53 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)) 09:13:53 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)) 09:13:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:13:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:13:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:13:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:00 INFO - Downloading blessings-1.6.tar.gz 09:16:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:16:00 INFO - Installing collected packages: blessings 09:16:00 INFO - Running setup.py install for blessings 09:16:00 INFO - Successfully installed blessings 09:16:00 INFO - Cleaning up... 09:16:00 INFO - Return code: 0 09:16:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:16:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:16:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:16:01 INFO - Reading from file tmpfile_stdout 09:16:01 INFO - Current package versions: 09:16:01 INFO - argparse == 1.2.1 09:16:01 INFO - blessings == 1.6 09:16:01 INFO - blobuploader == 1.2.4 09:16:01 INFO - docopt == 0.6.1 09:16:01 INFO - functools32 == 3.2.3-2 09:16:01 INFO - jsonschema == 2.5.1 09:16:01 INFO - manifestparser == 1.1 09:16:01 INFO - mozInstall == 1.12 09:16:01 INFO - mozcrash == 1.0 09:16:01 INFO - mozdebug == 0.1 09:16:01 INFO - mozdevice == 0.50 09:16:01 INFO - mozfile == 1.2 09:16:01 INFO - mozhttpd == 0.7 09:16:01 INFO - mozinfo == 0.9 09:16:01 INFO - mozleak == 0.1 09:16:01 INFO - mozlog == 3.4 09:16:01 INFO - moznetwork == 0.27 09:16:01 INFO - mozprocess == 0.25 09:16:01 INFO - mozprofile == 0.28 09:16:01 INFO - mozrunner == 6.13 09:16:01 INFO - mozscreenshot == 0.1 09:16:01 INFO - mozsystemmonitor == 0.3 09:16:01 INFO - moztest == 0.8 09:16:01 INFO - mozversion == 1.4 09:16:01 INFO - psutil == 3.1.1 09:16:01 INFO - requests == 1.2.3 09:16:01 INFO - wsgiref == 0.1.2 09:16:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:16:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13d11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x159da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1743770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1740a30>, '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 0x17417f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1741ca0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1494518303.374353-1973210711', '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 09:16:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:16:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:16:01 INFO - Using env: {'DISPLAY': ':0', 09:16:01 INFO - 'HOME': '/home/cltbld', 09:16:01 INFO - 'LANG': 'en_US.UTF-8', 09:16:01 INFO - 'LANGUAGE': 'en_US:en', 09:16:01 INFO - 'LOGNAME': 'cltbld', 09:16:01 INFO - 'MAIL': '/var/mail/cltbld', 09:16:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:01 INFO - 'MOZ_NO_REMOTE': '1', 09:16:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:01 INFO - 'NO_EM_RESTART': '1', 09:16:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:01 INFO - 'PWD': '/builds/slave/test', 09:16:01 INFO - 'SHELL': '/bin/bash', 09:16:01 INFO - 'SHLVL': '1', 09:16:01 INFO - 'TERM': 'linux', 09:16:01 INFO - 'TMOUT': '86400', 09:16:01 INFO - 'USER': 'cltbld', 09:16:01 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1494518303.374353-1973210711', 09:16:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:01 INFO - '_': '/tools/buildbot/bin/python'} 09:16:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:01 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)) 09:16:01 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)) 09:16:01 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)) 09:16:01 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)) 09:16:01 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)) 09:16:01 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)) 09:16:01 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)) 09:16:01 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)) 09:16:01 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)) 09:16:01 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)) 09:16:01 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)) 09:16:01 INFO - Cleaning up... 09:16:01 INFO - Return code: 0 09:16:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:16:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13d11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x159da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1743770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1740a30>, '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 0x17417f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1741ca0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1494518303.374353-1973210711', '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 09:16:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:16:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:16:01 INFO - Using env: {'DISPLAY': ':0', 09:16:01 INFO - 'HOME': '/home/cltbld', 09:16:01 INFO - 'LANG': 'en_US.UTF-8', 09:16:01 INFO - 'LANGUAGE': 'en_US:en', 09:16:01 INFO - 'LOGNAME': 'cltbld', 09:16:01 INFO - 'MAIL': '/var/mail/cltbld', 09:16:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:01 INFO - 'MOZ_NO_REMOTE': '1', 09:16:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:01 INFO - 'NO_EM_RESTART': '1', 09:16:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:01 INFO - 'PWD': '/builds/slave/test', 09:16:01 INFO - 'SHELL': '/bin/bash', 09:16:01 INFO - 'SHLVL': '1', 09:16:01 INFO - 'TERM': 'linux', 09:16:01 INFO - 'TMOUT': '86400', 09:16:01 INFO - 'USER': 'cltbld', 09:16:01 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1494518303.374353-1973210711', 09:16:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:01 INFO - '_': '/tools/buildbot/bin/python'} 09:16:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:01 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:16:01 INFO - Cleaning up... 09:16:01 INFO - Return code: 0 09:16:01 INFO - Running post-action listener: _resource_record_post_action 09:16:01 INFO - Running post-action listener: _start_resource_monitoring 09:16:01 INFO - Starting resource monitoring. 09:16:01 INFO - [mozharness: 2017-05-11 16:16:01.588466Z] Finished create-virtualenv step (success) 09:16:01 INFO - [mozharness: 2017-05-11 16:16:01.588929Z] Running install step. 09:16:01 INFO - Running pre-action listener: _resource_record_pre_action 09:16:01 INFO - Running main action method: install 09:16:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:16:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:16:01 INFO - Reading from file tmpfile_stdout 09:16:01 INFO - Detecting whether we're running mozinstall >=1.0... 09:16:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:16:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:16:01 INFO - Reading from file tmpfile_stdout 09:16:01 INFO - Output received: 09:16:01 INFO - Usage: mozinstall [options] installer 09:16:01 INFO - Options: 09:16:01 INFO - -h, --help show this help message and exit 09:16:01 INFO - -d DEST, --destination=DEST 09:16:01 INFO - Directory to install application into. [default: 09:16:01 INFO - "/builds/slave/test"] 09:16:01 INFO - --app=APP Application being installed. [default: firefox] 09:16:01 INFO - mkdir: /builds/slave/test/build/application 09:16:01 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'] 09:16:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 09:16:17 INFO - Reading from file tmpfile_stdout 09:16:17 INFO - Output received: 09:16:17 INFO - /builds/slave/test/build/application/firefox/firefox 09:16:17 INFO - Running post-action listener: _resource_record_post_action 09:16:17 INFO - [mozharness: 2017-05-11 16:16:17.692345Z] Finished install step (success) 09:16:17 INFO - [mozharness: 2017-05-11 16:16:17.692622Z] Running run-tests step. 09:16:17 INFO - Running pre-action listener: _resource_record_pre_action 09:16:17 INFO - Running main action method: run_tests 09:16:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:16:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:16:17 INFO - Python 2.7.3 09:16:17 INFO - Return code: 0 09:16:17 INFO - grabbing minidump binary from tooltool 09:16:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:17 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 0x1740a30>, '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 0x17417f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1741ca0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:16:17 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 09:16:17 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 09:16:17 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 09:16:17 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:16:17 INFO - Return code: 0 09:16:17 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:16:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:16:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:16:17 INFO - ENV: RUST_BACKTRACE is now 1 09:16:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:16:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:16: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/VqHjFaJWSBWRUdUhiRa35Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-002', '--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 09:16: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/VqHjFaJWSBWRUdUhiRa35Q/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-002 --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 09:16:17 INFO - Using env: {'DISPLAY': ':0', 09:16:17 INFO - 'HOME': '/home/cltbld', 09:16:17 INFO - 'LANG': 'en_US.UTF-8', 09:16:17 INFO - 'LANGUAGE': 'en_US:en', 09:16:17 INFO - 'LOGNAME': 'cltbld', 09:16:17 INFO - 'MAIL': '/var/mail/cltbld', 09:16:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:16:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:16:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:17 INFO - 'MOZ_NO_REMOTE': '1', 09:16:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:16:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:17 INFO - 'NO_EM_RESTART': '1', 09:16:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:17 INFO - 'PWD': '/builds/slave/test', 09:16:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:16:17 INFO - 'RUST_BACKTRACE': '1', 09:16:17 INFO - 'SHELL': '/bin/bash', 09:16:17 INFO - 'SHLVL': '1', 09:16:17 INFO - 'TERM': 'linux', 09:16:17 INFO - 'TMOUT': '86400', 09:16:17 INFO - 'USER': 'cltbld', 09:16:17 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1494518303.374353-1973210711', 09:16:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:17 INFO - '_': '/tools/buildbot/bin/python'} 09:16: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/VqHjFaJWSBWRUdUhiRa35Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-002', '--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 09:16:17 INFO - mozversion application_buildid: 20170511151153 09:16:17 INFO - mozversion application_changeset: 3b96f277325747fe668ca8cd896d2f581238e4ee 09:16:17 INFO - mozversion application_display_name: Nightly 09:16:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:16:17 INFO - mozversion application_name: Firefox 09:16:17 INFO - mozversion application_remotingname: firefox 09:16:17 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:16:17 INFO - mozversion application_vendor: Mozilla 09:16:17 INFO - mozversion application_version: 55.0a1 09:16:17 INFO - mozversion platform_buildid: 20170511151153 09:16:17 INFO - mozversion platform_changeset: 3b96f277325747fe668ca8cd896d2f581238e4ee 09:16:17 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:16:17 INFO - mozversion platform_version: 55.0a1 09:16:17 INFO - using testdate: 1494519377 09:16:17 INFO - actual date: 1494519377 09:16:17 INFO - starting webserver on 'localhost:59688' 09:16:17 INFO - SUITE-START | Running 2 tests 09:16:17 INFO - TEST-START | dromaeo_css 09:16:17 INFO - operating with platform_type : linux_ 09:16:17 INFO - Initialising browser for dromaeo_css test... 09:16:17 INFO - TEST-INFO | started process 3854 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXyKnyf/profile http://localhost:59688/getInfo.html) 09:16:21 INFO - PID 3854 | __metrics Screen width/height:1600/1200 09:16:21 INFO - PID 3854 | colorDepth:24 09:16:21 INFO - PID 3854 | Browser inner width/height: 1024/695 09:16:21 INFO - PID 3854 | __metrics 09:16:23 INFO - TEST-INFO | 3854: exit 0 09:16:23 INFO - Browser initialized. 09:16:23 INFO - Running cycle 1/1 for dromaeo_css test... 09:16:23 INFO - TEST-INFO | started process 4024 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXyKnyf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:16:35 INFO - PID 4024 | RSS: Main: 154447872 09:16:35 INFO - PID 4024 | 09:19:41 INFO - PID 4024 | Cycle 1(1): loaded http://localhost:59688/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59688/tests/dromaeo/cssquery-ext.html) 09:19:43 INFO - PID 4024 | RSS: Main: 164777984 09:19:43 INFO - PID 4024 | 09:22:45 INFO - PID 4024 | Cycle 1(1): loaded http://localhost:59688/tests/dromaeo/cssquery-ext.html (next: http://localhost:59688/tests/dromaeo/cssquery-jquery.html) 09:22:45 INFO - PID 4024 | RSS: Main: 161050624 09:22:45 INFO - PID 4024 | 09:25:47 INFO - PID 4024 | Cycle 1(1): loaded http://localhost:59688/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59688/tests/dromaeo/cssquery-mootools.html) 09:25:48 INFO - PID 4024 | RSS: Main: 168058880 09:25:48 INFO - PID 4024 | 09:28:50 INFO - PID 4024 | Cycle 1(1): loaded http://localhost:59688/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59688/tests/dromaeo/cssquery-prototype.html) 09:28:50 INFO - PID 4024 | RSS: Main: 160739328 09:28:50 INFO - PID 4024 | 09:31:53 INFO - PID 4024 | Cycle 1(1): loaded http://localhost:59688/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59688/tests/dromaeo/cssquery-yui.html) 09:31:54 INFO - PID 4024 | RSS: Main: 165240832 09:31:54 INFO - PID 4024 | 09:34:56 INFO - PID 4024 | Cycle 1(1): loaded http://localhost:59688/tests/dromaeo/cssquery-yui.html (next: http://localhost:59688/tests/dromaeo/cssquery-dojo.html) 09:34:56 INFO - PID 4024 | RSS: Main: 168435712 09:34:56 INFO - PID 4024 | 09:34:56 INFO - PID 4024 | __start_tp_report 09:34:56 INFO - PID 4024 | _x_x_mozilla_page_load 09:34:56 INFO - PID 4024 | _x_x_mozilla_page_load_details 09:34:56 INFO - PID 4024 | |i|pagename|runs| 09:34:56 INFO - PID 4024 | |0;dojo.html;2753.0777189641467;2758.3104223944015;2773.2373597260753;2787.8606069696516;2796.650418697664;5117.232415137732;5245.475452454757;5246.1868410396355;5258.710770907599;5262.210668399737;5178.715170665612;5226.55574276187;5272.762725677344;5279.155335146377;5339.439358867321;4671.9766401168035;4674.90650186996;4686.320483529886;4692.178868697982;4738.881527961794;4713;4738.312944623028;4738.715677059387;4754.38193034905;4771.522847715218;4566.086782643451;4623.214053610895;4646.628269738413;4696.976515117437;4702.5297470253045;4640.071985602893;4650.232711602582;4667.276572131291;4705.55297246761;4715.693479923794;4594.173048851206;4637.675362724611;4646.976765116153;4651.5813576778255;4675.251959686434;5150.407703114152;5153.252778347156;5202.817901373433;5227.8431647050265;5228.163493840967;5626.324841019104;5638.548916086703;5638.577106716972;5645.491905728504;5654.293213348332;5624.71876406178;5625.634333768292;5653.773849046033;5672.9505041567445;5689.9146512802345;5162.793488260428;5230.503102205323;5235.65968212065;5240.502152295526;5244.763985620656;5733.226014488074;5791.494211504954;5814.040683287295;5829.329627092854;5844.766209351664;5488.890222195613;5552.611317207838;5573.526250268692;5592.3009623797025;5598.776048958078;5620.775168993195;5728.971355143281;5733.655980641175;5734.627249228787;5743.8564035899435;5601.5238704709745;5616.1014237721765;5621.0444224481935;5626.043572592669;5638.097904335286;5462.426445223274;5602.327720673546;5605.971970140205;5606.523445507178;5607.635503692247;4618.030213655103;4621.792019359137;4626.4910859805395;4627.5835174833755;4644.373009643722;4019.959800402017;4038.5759495253164;4062.3500239961463;4073.7759423232005;4124.484439445069;4877.6829506081995;4918.163912134945;4918.262260660929;4936.456989731127;4983.825566105242;5705.857353566194;5709.457601527848;5715.371309155989;5733.426657334317;5739.598228124075;4842.225243960949;4897.632677549198;4908.067467181224;4917.950820491821;4928.162212423825;4851.102546028926;4958.677685950402;4965.255211718199;4966.6771659842;4972.701637901738;5241.344831896013;5286.074936886106;5286.7356632169;5289.259503669567;5310.9734451327495;5599.04816181242;5702.116171993348;5716.742746576331;5748.597598168088;5775.306963164448;5243.108671525773;5305.761240744098;5374.785008599769;5379.515843574097;5403.648762830403;5597.384287728428;5646.661200327829;5671.631343962629;5700.543956483389;5701.828945131652;5613.971930140323;5698.715064246689;5739.339975902824;5747.597668163188;5750.856228594319;5476.863078423071;5477.1510415885605;5524.447555244444;5544.916826247691;5546.805861794979;5495.752691128829;5526.309211348582;5539.723013849372;5541.141123125922;5542.057850165401;5307.9734601326745;5403.675779453245;5434.1033729435785;5434.239206511171;5493.176023596492;5510.201020852034;5510.531604813636;5519.31008623922;5536.91694624589;5540.307461567304;5331.280277162688;5379.757910893941;5383.623146379872;5383.703896285742;5394.9460505394445 09:34:56 INFO - PID 4024 | |1;ext.html;629.2323365494099;705.9611721355326;724.5073350121917;1142.6000899685112;1193.8686744458103;33456.83271583642;34102.317953640915;34132.1466963326;34137.82931085344;34270.48594271088;32126;32458;32458.02625921226;32645.18387040319;32686;6519.511036672245;6656.534042617019;6866.896996545056;7010.6494675266285;7040.119985001875;6874.759383421581;6977.546459480143;7017.438604911595;7018.859622807541;7034.718611255569;6904.4131248843905;7003.754868579601;7035.331643493885;7056.894146587805;7060.646967651623;6973.616451095188;7003.47473939454;7004.5797252164775;7042.119735033121;7047.647617619098;6868.759593414256;6883.862322753517;6930.896036559456;6958.0954475918315;6959.826004349881;31266.843665781755;31601.52597711013;31623.525647115082;31726;31805.204869878205;3057.1745628680133;3103.6275646922513;3113.050519591524;3117.8285194314303;3123;24396.390090247707;24735.505289894103;24763.62854557183;24800.627990580157;24810.627840582405;3100.3644252928098;3162.7153556180056;3189.6012998375204;3196.5844440222854;3218.678132186786;142799;143360;144284.27857860748;149212;153010;39629.80185099056;39700;39837.80081099634;39984;40151.19697606032;41163;41588.79205603953;41647;41890.37164442536;44142.33786493205;12661.050421218446;12691.873081269254;12702.682432939066;12771.042171837149;12778.744425111445;10080.143187829117;10146.239032072624;10170.796584068277;10190.796184076276;10230.744231394274;12887.09790314669;12903.032272579407;12903.161294516014;12921.612351629467;13069.215847049018;5298.629095963245;5330.227117068003;5389.649672771257;5474.124140137559;5478.342598888159;744.9106107267054;817.1787353709484;823.8681810910346;835.4736515994884;852.7527017165077;19892.45182118851;21283.680744788842;21333.78666213349;21454.46363840885;21678;894.695803426838;898.874157617934;899.3434792601438;901.7475106970059;926.4719110107309;10513.84229236562;10738.516766745515;10918.07196388316;11107.888921110847;11173.608923687794;173591.13204433897;174993;175088;175212;175340;40638.59361406407;40651.79674101611;40833.59166408298;40990.59009409927;41059.1788164241;1211.4669545399995;1228.225214564963;1234.2039384597065;1237.5854088880146;1237.789575772121;18885.71671424957;19328.22687092557;20463.18147274152;20465.897670511553;20615.8969205159;30202.395952080395;37550;37706.2458750818;37862.242755144194;38120;158047;159193.2040339791;159814;160016;160072;30390.848045760515;30868.845655772475;31008.58905520436;31099.378012440076;31123.688763112987;36447.81776091103;37445;37540.2491950154;37831.62168378281;37941;27572.44855102927;27839.860800695868;27998;28430;29108.56337154902;27500.724992749816;27562.724372756817;28275.293117672223;28811.855940721;28977.7102228975;30666;30754.384912301182;31032.689673103883;31040.689593104682;31066;26795.598066028637;27027.189184324503;27452.313692157855;27675.308117297227;28442.715572844834;20048;22797.658035129156;22826.54346913086;22971.88514057419;22987.770122299233 09:34:56 INFO - PID 4024 | |2;jquery.html;412.21267379305533;432.58687953004886;436.1255682356876;437.67611967144325;451.42668810610525;17003;17075.82924170758;17308.307667693276;17426.124689426244;17720.379786707468;15618.76571851423;15764.76352854708;15802.367905283803;15857.207139643;16438.342466301365;5362.069654924224;5366.382865970405;5447.264619276387;5525.336959564837;5534.197541356501;5318.601104917147;5393.919091213635;5442.727863606824;5486.149646804752;5545.057340252147;5502.284702988606;5505.697186654733;5511.944880551203;5534.75093620786;5536.944630553703;5371;5445.891082178354;5459.836204913859;5467.097928841737;5511.173324001392;4962.106820772259;4971.850844474636;4972.229304457815;4991.401031876162;5000.874978125539;21376.572468550698;22360.776392236032;22470.213542525933;22644.88677556618;22943.082276708912;1600.8719302455893;1627.1213544685975;1627.7476991066403;1631.5023917705096;1635.9366411832286;14640.267986600627;14734.852651473455;14767.630809229855;14920.403183872631;14985.700285994328;2050.559129787088;2120.724305840231;2132.637451633226;2159.0392275437434;2174.1086154676505;77206;86937.35127677016;88143;98595.50702246514;98921.50539247258;10358.53386597605;10412.32319899204;10498;10591.417472039111;10628.787424251472;15293.694126117638;15301.08193508393;15309;15340.232988350537;15361.385544578316;7357.448191385667;7589.924100759032;7663.233676632292;7739.110002349689;7796.337311328601;6855.108835851407;6901.827454313682;6990.6155161465595;7055.082839230867;7340.0457940467395;6540.117084193668;6612.702428390734;6764.830879228059;6765.729370825112;6825.918094981079;1902.3627084926427;1909.627622613577;1925.5186203449139;1925.8844469331605;2034.9694754578693;836.4395854777314;869.7909905231733;886.0652012127139;903.9864402033975;907.6142639378238;17184.742228866577;17320.393786217675;17644.382446614305;17726.556836079202;19831.206751730057;872.2367928062945;879.1955360844755;882.1134759566722;883.1257055514994;906.3157316226207;9198;9210.401323914059;11091.826839396243;12019.158658893968;12077.15459917815;344530;349262;353754;358668;360103;76761;81428.59285703534;82035;82083.17916820875;82101.17898820936;1069.6202847989007;1084.1760262200785;1129.8587676540433;1132.9546818127346;1138.2828817844704;17097.48707538776;17212.913935430744;17884.821151788317;18491.260349585868;18690.719639205152;37967;38354.232915342094;38919.6108038916;38951.22097557976;39379;49515.75242123767;49521.75239123782;49678;49704.50295496999;50789;8238.546879921421;8278.503289802631;8280.420370574002;8622.568871556523;8629.266512346521;12785.488580456678;13122.868771312547;13127.737445251232;13224.867751322749;13369;11328.546858125583;11374.94312528432;11402;11408.600698975495;11570.132240081859;9453.101955314234;9476.620935162517;9762.267829912644;10593.576256949636;10786.946065269623;11184.496697648463;11334.60328888485;11343.829842552534;11477.655670329903;11612.94193529027;8260.421770476009;8323.084460709197;8371.539565323965;8448.619812108347;8467.449615774955;11310.491027903929;11484.712882177679;11527.250728702942;11532.59635912739;11763.647090587296 09:34:56 INFO - PID 4024 | |3;mootools.html;1264.1846009323986;1316.1510825517541;1326.542342891702;1329.9534516291926;1334.6062911441127;9867.309288349818;10292.742681432968;10294.073533381994;10424.270301078928;10639.202059845504;10429.374237545753;10494.632687855927;10570.735731606714;10599.152067834573;10615.522301496432;2263.1739415113448;2291.0606651272983;2303.4221558232666;2307.261676263596;2311.838171328008;2187.5843589717983;2239.428945618865;2243.2372993182316;2244.652078927769;2245.236619549353;2255.7857003584713;2265.3543740034092;2276.4422716434497;2299.9195028174017;2312.8843557822124;2138.6791981202946;2222.911083556664;2238.328501449567;2245.292732789166;2247.1910112359537;2106.6734656128324;2192.7697591752794;2218.035154707691;2218.4564781628605;2240.955180896373;10211.336263142872;10217;10272.948635256776;10278.7430314242;10301.072903438726;5726.455986681259;5891.499222566087;5929.466348028655;5938.8218353449465;5950.226470558843;3323.202431416467;3472.58329000519;3490.633483484223;3548.9112772180642;3556.928861422757;1093.704699731068;1140.749035212252;1146.8222425523973;1154.7344110854467;1161.4134861894775;15179;15377;15416.691666166726;15431.537053888402;15536.456224032105;4750;4904.362432883702;5129.666571672829;5163.612729045337;5164.92252616211;10979.341239525655;10995.065419439277;11011.944940275247;11031.83452248217;11037.227394082338;5597.832065038056;5613.522850557748;5615.6630602164005;5617.747201375948;5628.296462942132;5172.482751724798;5173.094708426011;5194.168932970707;5319.228711836762;5409.891802163935;3811.8856434306463;3970.642642162219;4006.939895901564;4043.555208927016;4069.5320038195987;1150.3672979861044;1150.5800382860364;1173.2549830857297;1174.1135442740847;1174.9118816088658;690.8963655451722;720.8846584546448;723.1430754555868;734.1043926409878;743.4387037786545;5585.804496842591;5665.915011274834;5733.31200255972;5763.682997435181;5787.363390027093;527.2907938822278;532.7815595605783;540.3893600231713;549.6784381136999;550.1005855426326;3523.8061906595385;3527.4532447470683;3562.87529936451;3586.5875424326014;3726.7018638508853;13282.468701251843;13533.796993045113;13735.175889446666;13798.793018104736;13810.516631917835;13093.541726039542;13548;13608.795868061987;13667.63323667645;13709.588712338445;7599.430042746816;7612.695492180251;7682.73110441143;7702.306792388712;7716.922830771733;5719.685417302088;5773.566982476247;5798.478136967693;5861.413858614104;5962.2249107615735;12112.697182570506;12115.212511186744;12118.515259389525;12224.083193760325;12372.752544948871;12188.20776649515;12506.18709783861;12636.178648387826;12726.300053497147;12827.102102853083;9527.142557169887;9677.370970887152;9779.26655500826;9794.26543009263;9819;9451.763705907408;9496.477693726634;9551.283653725859;9563.187129094105;9585.760355991157;8334.833303333779;8368.20502052304;8536.103709110703;8582.184692454206;8610.61252243638;8239.588020599065;8241.546714930737;8340.582970851554;8434.578271086544;8545.401821872409;7587.393008559192;7711.9614401929875;7723.729669461542;7727.729529466442;7737.845243095219;5446.645968012067;5459.317585301837;5530.889382212414;5568.888622227614;5674.526708913572;5699.34457537372;5714.942850571441;5743.8851222976145;5846.766129354778;5925.851853703692 09:34:56 INFO - PID 4024 | |4;prototype.html;392.90373858404695;397.61431411530816;398.34472293077886;401.1275475840048;409.27762499188935;5511.421300763417;5550.472705093015;5557.666540007603;5567.8886422271535;5574.9721251393685;5304.973475132619;5318.937009442602;5393.892122157555;5419.078756611376;5420.430854760252;4248.150369926012;4255.9787201063955;4256.2338779019765;4262.957370426287;4268.40242366069;4260.169266992938;4261.360795880627;4262.208253593901;4284.100338928829;4291.163223171483;4136.66906647469;4194.832206711728;4246.341817018367;4252.702310838243;4254.87235382939;4218.704690671654;4220.78896055199;4226.704130710851;4235;4266.87199384019;3828.755654412327;3854.075021994729;3854.1906199698096;3870.651641352292;3878.26313000529;5177.197534382177;5329.680219186823;5344.732763361816;5352.2506849041165;5354.089804733166;4980.850574482735;4987.850364489072;4996.1756310208775;4996.525330093636;4999.100161970844;5730;5733;5741.368449470555;5747;5749.396313387076;4234.87295381139;4258.318669012943;4298.548652391485;4307;4337.327714204303;5857.355690874;6005.279366476007;6013.75944962201;6021.548383871227;6035.758569657209;4072.6945479089186;4094.242565125461;4094.6724262058965;4105.486814148231;4108.732932359387;4167.312393455107;4204.768737719455;4206.978965105216;4207.537170911197;4209.400427837463;4730.432348118248;4748.0978614063215;4822.638302127354;4828.734419606885;4829.855104346875;5708.343540492813;5716.714164291852;5733.655980641175;5743.913841292384;5766;1411.6329754263966;1423.2172305232082;1424.722179175051;1425.7026106243477;1429.685469196775;911.5123408976299;944.7543638653999;960.3325688646323;973.493783232715;978.1245785022365;5093.733288686573;5094.2358646203365;5101.566366858859;5112.376385975472;5123.359580052494;5940.970295148497;5966.731497082555;5971.5222782177325;6027.60820546663;6035.637861728311;4897.706137631753;5051.4695956924725;5084.923726144111;5103.464136265713;5130.563902068291;3158.8104713717253;3193.0101668482844;3194.7923384979904;3201.2477067889026;3204.9359012819614;5473.534749546333;5611.775528978795;5624.29696287964;5637.7181140942785;5639.718014099279;6164.520515076439;6168.938310616836;6168.938310616926;6174.5060395168275;6175.783847565403;5538.889222215533;5571.832845014656;5598.692071936082;5656.123300888369;5670.8941756357935;5851.502622277154;5938.901303258911;6011.81964541047;6020.819375418744;6032.642655402499;5845.866258815587;5848.298204215522;5877.206577112035;5894.0274854648505;5910.911336330045;5446.428125046813;5593.524550413261;5628.9471580462005;5632.690202038763;5636.718164091697;4124.814383352817;4128.483939507561;4130.421740956329;4143.81352839117;4147.2534943710425;5892.587518873637;6017.518598512021;6035.517158627387;6039.245094363205;6039.278805540575;5747.971260143672;5769.53843692512;5772.538196944319;5781.219535362672;5813.680247586423;5772.855678607901;5777.162311464898;5792.4497172768515;5793.536517078708;5828.912566311424;5525.723713814374;5709;5719.685417302088;5721.5136713379825;5733.254676891977;5829.59192856496;5836.708164591839;5847.649141051551;5860.882782344414;5865.296164460292;5545.473180047889;5666.405027472221;5667.971660141838;5684.175794509855;5687.516561092353 09:34:56 INFO - PID 4024 | |5;yui.html;711.4664001998501;726.0923845193508;732.6226993098555;758.7003133762164;775.0621747685299;12517.248965062103;13129;13653.522126725567;14580.343884525182;14660.853391466095;14396.208208548553;14539.49111781088;14894.404223831061;14933.775993360108;15035.924820375883;881.3389957531836;900.6442455230181;905.0135352465811;905.5427009360296;906.3383729877194;913.2967614936495;914.9451032938011;915.50562696144;915.922146617535;919.5218486387075;955.961761529538;958.324381311174;959.7744530035436;965.3580369054571;966.8839739231267;946.0397696338222;949.715085474355;950.396498223633;952.3190918493276;954.804265125651;696.9163700355939;697.6372286411089;701.5860642221063;702.6065403374075;704.9330313620197;14321.140731556035;14340.56978290653;14855.2572371381;15409.768853467207;15530.378784848592;15688.921555392264;16014.759778603331;16217.432389866415;16705.415310464195;16787.0767107809;10602.469876506144;11081.390523521202;11140.275882067639;12407.565735199225;12562.05784566152;2926.1514160893316;2951.719586639287;2955.689652586469;2955.881764729408;2956.9556456653168;66491.33508664949;66654.66672666655;66949.3305066948;67203.3279667202;68880.6555967222;12065.336406497727;12210.877891221151;12354.25874447536;12365.938170309271;12411.193272437262;21683.891580542313;21747;22545.661815072788;22909;22918.65622015671;5697.145428185805;5752.33848107473;5801.970990145107;5813.7383817728305;6534.4445722113205;2974.256435891027;3048.9847550762106;3058.403611295776;3076.2770748873995;3093.1648454917045;3541.327147841902;3609.242059167552;3643.744937854378;3664.340418724602;3811.942820857689;780.8282177920848;795.073739093949;796.8246985663145;800.087899794235;803.333233416263;604.1813342920279;616.6269407008696;625.7622103600603;627.6673363117555;648.0538413915642;9623.326367154232;10301.74245643865;10535.104516116216;10653.307535010199;10959.726006849893;576.8442520519436;578.4735890339771;583.2301362201853;584.476893180601;584.5995493087249;4459.710118842265;4546.4544254689645;4549.681522293407;4567.132244873464;4577.908441831211;58124.418755812745;58791.41208587945;59741.701291493264;60751;63156.68421657954;50730.23904641433;51408.74295628498;51858.22212666813;52251;52592;47941.5205847944;52367.73816130895;53658.73170634122;53707.462925370244;53766.73116634392;9897.25770567196;10365;10917.890821092005;10970;11275.267107637977;27201;27261.72738272592;27952.44095118127;28382.432351352443;28686;32773;33128;33269;33451.83274083614;33613.66386336106;13967.022308438718;14164.575062748134;14189.361478733688;14287.785683214968;14372.35324410425;16223.837761622233;16527.091009994565;16630;17016.914915425343;17309.22108505095;14356.138631682132;15565.210000850047;15832;15915.761263580824;16066.43767468133;15207.923960380569;15325.080495170325;15488.922555387151;15513.37946482128;15567.688646226785;14297.428102875769;14454.132752034911;15255.771163432779;15716.528504144893;15870.761938571162;10456.68629941103;11134.88865111371;11181.776364472502;11549.769004620028;11750.29498230109;12137.635870923887;12925.870741292843;13007.739845202854;13160.868391316348;13163 09:34:56 INFO - PID 4024 | __end_tp_report 09:34:56 INFO - PID 4024 | __start_cc_report 09:34:56 INFO - PID 4024 | _x_x_mozilla_cycle_collect,360 09:34:56 INFO - PID 4024 | __end_cc_report 09:34:56 INFO - PID 4024 | __startTimestamp1494520496323__endTimestamp 09:34:56 INFO - PID 4024 | 09:34:56 INFO - PID 4024 | ------- Summary: start ------- 09:34:56 INFO - PID 4024 | Number of tests: 6 09:34:56 INFO - PID 4024 | 09:34:56 INFO - PID 4024 | [#0] dojo.html Cycles:165 Average:5178.34 Median:5331.28 stddev:605.48 (11.4%) stddev-sans-first:576.67 09:34:56 INFO - PID 4024 | Values: 2753.1 2758.3 2773.2 2787.9 2796.7 5117.2 5245.5 5246.2 5258.7 5262.2 5178.7 5226.6 5272.8 5279.2 5339.4 4672.0 4674.9 4686.3 4692.2 4738.9 4713.0 4738.3 4738.7 4754.4 4771.5 4566.1 4623.2 4646.6 4697.0 4702.5 4640.1 4650.2 4667.3 4705.6 4715.7 4594.2 4637.7 4647.0 4651.6 4675.3 5150.4 5153.3 5202.8 5227.8 5228.2 5626.3 5638.5 5638.6 5645.5 5654.3 5624.7 5625.6 5653.8 5673.0 5689.9 5162.8 5230.5 5235.7 5240.5 5244.8 5733.2 5791.5 5814.0 5829.3 5844.8 5488.9 5552.6 5573.5 5592.3 5598.8 5620.8 5729.0 5733.7 5734.6 5743.9 5601.5 5616.1 5621.0 5626.0 5638.1 5462.4 5602.3 5606.0 5606.5 5607.6 4618.0 4621.8 4626.5 4627.6 4644.4 4020.0 4038.6 4062.4 4073.8 4124.5 4877.7 4918.2 4918.3 4936.5 4983.8 5705.9 5709.5 5715.4 5733.4 5739.6 4842.2 4897.6 4908.1 4918.0 4928.2 4851.1 4958.7 4965.3 4966.7 4972.7 5241.3 5286.1 5286.7 5289.3 5311.0 5599.0 5702.1 5716.7 5748.6 5775.3 5243.1 5305.8 5374.8 5379.5 5403.6 5597.4 5646.7 5671.6 5700.5 5701.8 5614.0 5698.7 5739.3 5747.6 5750.9 5476.9 5477.2 5524.4 5544.9 5546.8 5495.8 5526.3 5539.7 5541.1 5542.1 5308.0 5403.7 5434.1 5434.2 5493.2 5510.2 5510.5 5519.3 5536.9 5540.3 5331.3 5379.8 5383.6 5383.7 5394.9 09:34:56 INFO - PID 4024 | 09:34:56 INFO - PID 4024 | [#1] ext.html Cycles:180 Average:30727.49 Median:17118.37 stddev:41451.57 (242.1%) stddev-sans-first:41506.24 09:34:56 INFO - PID 4024 | Values: 629.2 706.0 724.5 1142.6 1193.9 33456.8 34102.3 34132.1 34137.8 34270.5 32126.0 32458.0 32458.0 32645.2 32686.0 6519.5 6656.5 6866.9 7010.6 7040.1 6874.8 6977.5 7017.4 7018.9 7034.7 6904.4 7003.8 7035.3 7056.9 7060.6 6973.6 7003.5 7004.6 7042.1 7047.6 6868.8 6883.9 6930.9 6958.1 6959.8 31266.8 31601.5 31623.5 31726.0 31805.2 3057.2 3103.6 3113.1 3117.8 3123.0 24396.4 24735.5 24763.6 24800.6 24810.6 3100.4 3162.7 3189.6 3196.6 3218.7 142799.0 143360.0 144284.3 149212.0 153010.0 39629.8 39700.0 39837.8 39984.0 40151.2 41163.0 41588.8 41647.0 41890.4 44142.3 12661.1 12691.9 12702.7 12771.0 12778.7 10080.1 10146.2 10170.8 10190.8 10230.7 12887.1 12903.0 12903.2 12921.6 13069.2 5298.6 5330.2 5389.6 5474.1 5478.3 744.9 817.2 823.9 835.5 852.8 19892.5 21283.7 21333.8 21454.5 21678.0 894.7 898.9 899.3 901.7 926.5 10513.8 10738.5 10918.1 11107.9 11173.6 173591.1 174993.0 175088.0 175212.0 175340.0 40638.6 40651.8 40833.6 40990.6 41059.2 1211.5 1228.2 1234.2 1237.6 1237.8 18885.7 19328.2 20463.2 20465.9 20615.9 30202.4 37550.0 37706.2 37862.2 38120.0 158047.0 159193.2 159814.0 160016.0 160072.0 30390.8 30868.8 31008.6 31099.4 31123.7 36447.8 37445.0 37540.2 37831.6 37941.0 27572.4 27839.9 27998.0 28430.0 29108.6 27500.7 27562.7 28275.3 28811.9 28977.7 30666.0 30754.4 31032.7 31040.7 31066.0 26795.6 27027.2 27452.3 27675.3 28442.7 20048.0 22797.7 22826.5 22971.9 22987.8 09:34:56 INFO - PID 4024 | 09:34:56 INFO - PID 4024 | [#2] jquery.html Cycles:180 Average:24634.44 Median:38160.62 stddev:59128.72 (154.9%) stddev-sans-first:59266.63 09:34:56 INFO - PID 4024 | Values: 412.2 432.6 436.1 437.7 451.4 17003.0 17075.8 17308.3 17426.1 17720.4 15618.8 15764.8 15802.4 15857.2 16438.3 5362.1 5366.4 5447.3 5525.3 5534.2 5318.6 5393.9 5442.7 5486.1 5545.1 5502.3 5505.7 5511.9 5534.8 5536.9 5371.0 5445.9 5459.8 5467.1 5511.2 4962.1 4971.9 4972.2 4991.4 5000.9 21376.6 22360.8 22470.2 22644.9 22943.1 1600.9 1627.1 1627.7 1631.5 1635.9 14640.3 14734.9 14767.6 14920.4 14985.7 2050.6 2120.7 2132.6 2159.0 2174.1 77206.0 86937.4 88143.0 98595.5 98921.5 10358.5 10412.3 10498.0 10591.4 10628.8 15293.7 15301.1 15309.0 15340.2 15361.4 7357.4 7589.9 7663.2 7739.1 7796.3 6855.1 6901.8 6990.6 7055.1 7340.0 6540.1 6612.7 6764.8 6765.7 6825.9 1902.4 1909.6 1925.5 1925.9 2035.0 836.4 869.8 886.1 904.0 907.6 17184.7 17320.4 17644.4 17726.6 19831.2 872.2 879.2 882.1 883.1 906.3 9198.0 9210.4 11091.8 12019.2 12077.2 344530.0 349262.0 353754.0 358668.0 360103.0 76761.0 81428.6 82035.0 82083.2 82101.2 1069.6 1084.2 1129.9 1133.0 1138.3 17097.5 17212.9 17884.8 18491.3 18690.7 37967.0 38354.2 38919.6 38951.2 39379.0 49515.8 49521.8 49678.0 49704.5 50789.0 8238.5 8278.5 8280.4 8622.6 8629.3 12785.5 13122.9 13127.7 13224.9 13369.0 11328.5 11374.9 11402.0 11408.6 11570.1 9453.1 9476.6 9762.3 10593.6 10786.9 11184.5 11334.6 11343.8 11477.7 11612.9 8260.4 8323.1 8371.5 8448.6 8467.4 11310.5 11484.7 11527.3 11532.6 11763.6 09:34:56 INFO - PID 4024 | 09:34:56 INFO - PID 4024 | [#3] mootools.html Cycles:180 Average:6462.77 Median:3559.90 stddev:4157.42 (116.8%) stddev-sans-first:4150.73 09:34:56 INFO - PID 4024 | Values: 1264.2 1316.2 1326.5 1330.0 1334.6 9867.3 10292.7 10294.1 10424.3 10639.2 10429.4 10494.6 10570.7 10599.2 10615.5 2263.2 2291.1 2303.4 2307.3 2311.8 2187.6 2239.4 2243.2 2244.7 2245.2 2255.8 2265.4 2276.4 2299.9 2312.9 2138.7 2222.9 2238.3 2245.3 2247.2 2106.7 2192.8 2218.0 2218.5 2241.0 10211.3 10217.0 10272.9 10278.7 10301.1 5726.5 5891.5 5929.5 5938.8 5950.2 3323.2 3472.6 3490.6 3548.9 3556.9 1093.7 1140.7 1146.8 1154.7 1161.4 15179.0 15377.0 15416.7 15431.5 15536.5 4750.0 4904.4 5129.7 5163.6 5164.9 10979.3 10995.1 11011.9 11031.8 11037.2 5597.8 5613.5 5615.7 5617.7 5628.3 5172.5 5173.1 5194.2 5319.2 5409.9 3811.9 3970.6 4006.9 4043.6 4069.5 1150.4 1150.6 1173.3 1174.1 1174.9 690.9 720.9 723.1 734.1 743.4 5585.8 5665.9 5733.3 5763.7 5787.4 527.3 532.8 540.4 549.7 550.1 3523.8 3527.5 3562.9 3586.6 3726.7 13282.5 13533.8 13735.2 13798.8 13810.5 13093.5 13548.0 13608.8 13667.6 13709.6 7599.4 7612.7 7682.7 7702.3 7716.9 5719.7 5773.6 5798.5 5861.4 5962.2 12112.7 12115.2 12118.5 12224.1 12372.8 12188.2 12506.2 12636.2 12726.3 12827.1 9527.1 9677.4 9779.3 9794.3 9819.0 9451.8 9496.5 9551.3 9563.2 9585.8 8334.8 8368.2 8536.1 8582.2 8610.6 8239.6 8241.5 8340.6 8434.6 8545.4 7587.4 7712.0 7723.7 7727.7 7737.8 5446.6 5459.3 5530.9 5568.9 5674.5 5699.3 5714.9 5743.9 5846.8 5925.9 09:34:56 INFO - PID 4024 | 09:34:56 INFO - PID 4024 | [#4] prototype.html Cycles:180 Average:4797.59 Median:5459.98 stddev:1399.56 (25.6%) stddev-sans-first:1363.88 09:34:56 INFO - PID 4024 | Values: 392.9 397.6 398.3 401.1 409.3 5511.4 5550.5 5557.7 5567.9 5575.0 5305.0 5318.9 5393.9 5419.1 5420.4 4248.2 4256.0 4256.2 4263.0 4268.4 4260.2 4261.4 4262.2 4284.1 4291.2 4136.7 4194.8 4246.3 4252.7 4254.9 4218.7 4220.8 4226.7 4235.0 4266.9 3828.8 3854.1 3854.2 3870.7 3878.3 5177.2 5329.7 5344.7 5352.3 5354.1 4980.9 4987.9 4996.2 4996.5 4999.1 5730.0 5733.0 5741.4 5747.0 5749.4 4234.9 4258.3 4298.5 4307.0 4337.3 5857.4 6005.3 6013.8 6021.5 6035.8 4072.7 4094.2 4094.7 4105.5 4108.7 4167.3 4204.8 4207.0 4207.5 4209.4 4730.4 4748.1 4822.6 4828.7 4829.9 5708.3 5716.7 5733.7 5743.9 5766.0 1411.6 1423.2 1424.7 1425.7 1429.7 911.5 944.8 960.3 973.5 978.1 5093.7 5094.2 5101.6 5112.4 5123.4 5941.0 5966.7 5971.5 6027.6 6035.6 4897.7 5051.5 5084.9 5103.5 5130.6 3158.8 3193.0 3194.8 3201.2 3204.9 5473.5 5611.8 5624.3 5637.7 5639.7 6164.5 6168.9 6168.9 6174.5 6175.8 5538.9 5571.8 5598.7 5656.1 5670.9 5851.5 5938.9 6011.8 6020.8 6032.6 5845.9 5848.3 5877.2 5894.0 5910.9 5446.4 5593.5 5628.9 5632.7 5636.7 4124.8 4128.5 4130.4 4143.8 4147.3 5892.6 6017.5 6035.5 6039.2 6039.3 5748.0 5769.5 5772.5 5781.2 5813.7 5772.9 5777.2 5792.4 5793.5 5828.9 5525.7 5709.0 5719.7 5721.5 5733.3 5829.6 5836.7 5847.6 5860.9 5865.3 5545.5 5666.4 5668.0 5684.2 5687.5 09:34:56 INFO - PID 4024 | 09:34:56 INFO - PID 4024 | [#5] yui.html Cycles:180 Average:15219.29 Median:3067.34 stddev:17191.91 (560.5%) stddev-sans-first:17205.61 09:34:56 INFO - PID 4024 | Values: 711.5 726.1 732.6 758.7 775.1 12517.2 13129.0 13653.5 14580.3 14660.9 14396.2 14539.5 14894.4 14933.8 15035.9 881.3 900.6 905.0 905.5 906.3 913.3 914.9 915.5 915.9 919.5 956.0 958.3 959.8 965.4 966.9 946.0 949.7 950.4 952.3 954.8 696.9 697.6 701.6 702.6 704.9 14321.1 14340.6 14855.3 15409.8 15530.4 15688.9 16014.8 16217.4 16705.4 16787.1 10602.5 11081.4 11140.3 12407.6 12562.1 2926.2 2951.7 2955.7 2955.9 2957.0 66491.3 66654.7 66949.3 67203.3 68880.7 12065.3 12210.9 12354.3 12365.9 12411.2 21683.9 21747.0 22545.7 22909.0 22918.7 5697.1 5752.3 5802.0 5813.7 6534.4 2974.3 3049.0 3058.4 3076.3 3093.2 3541.3 3609.2 3643.7 3664.3 3811.9 780.8 795.1 796.8 800.1 803.3 604.2 616.6 625.8 627.7 648.1 9623.3 10301.7 10535.1 10653.3 10959.7 576.8 578.5 583.2 584.5 584.6 4459.7 4546.5 4549.7 4567.1 4577.9 58124.4 58791.4 59741.7 60751.0 63156.7 50730.2 51408.7 51858.2 52251.0 52592.0 47941.5 52367.7 53658.7 53707.5 53766.7 9897.3 10365.0 10917.9 10970.0 11275.3 27201.0 27261.7 27952.4 28382.4 28686.0 32773.0 33128.0 33269.0 33451.8 33613.7 13967.0 14164.6 14189.4 14287.8 14372.4 16223.8 16527.1 16630.0 17016.9 17309.2 14356.1 15565.2 15832.0 15915.8 16066.4 15207.9 15325.1 15488.9 15513.4 15567.7 14297.4 14454.1 15255.8 15716.5 15870.8 10456.7 11134.9 11181.8 11549.8 11750.3 12137.6 12925.9 13007.7 13160.9 13163.0 09:34:56 INFO - PID 4024 | -------- Summary: end -------- 09:34:56 INFO - PID 4024 | 09:34:57 INFO - TEST-INFO | 4024: exit 0 09:34:57 INFO - TEST-OK | dromaeo_css | took 1119200ms 09:34:57 INFO - TEST-START | kraken 09:34:57 INFO - operating with platform_type : linux_ 09:34:57 INFO - Initialising browser for kraken test... 09:34:57 INFO - TEST-INFO | started process 4444 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpi6mm4U/profile http://localhost:59688/getInfo.html) 09:35:01 INFO - PID 4444 | __metrics Screen width/height:1600/1200 09:35:01 INFO - PID 4444 | colorDepth:24 09:35:01 INFO - PID 4444 | Browser inner width/height: 1024/695 09:35:01 INFO - PID 4444 | __metrics 09:35:03 INFO - TEST-INFO | 4444: exit 0 09:35:03 INFO - Browser initialized. 09:35:03 INFO - Running cycle 1/1 for kraken test... 09:35:03 INFO - TEST-INFO | started process 4618 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpi6mm4U/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:35:15 INFO - PID 4618 | RSS: Main: 155152384 09:35:15 INFO - PID 4618 | 09:35:17 INFO - PID 4618 | Cycle 1(1): loaded http://localhost:59688/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59688/tests/kraken/driver.html?testName=audio-beat-detection) 09:35:18 INFO - PID 4618 | RSS: Main: 161402880 09:35:18 INFO - PID 4618 | 09:35:21 INFO - PID 4618 | Cycle 1(1): loaded http://localhost:59688/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59688/tests/kraken/driver.html?testName=audio-dft) 09:35:21 INFO - PID 4618 | RSS: Main: 159809536 09:35:21 INFO - PID 4618 | 09:35:24 INFO - PID 4618 | Cycle 1(1): loaded http://localhost:59688/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59688/tests/kraken/driver.html?testName=audio-fft) 09:35:25 INFO - PID 4618 | RSS: Main: 157462528 09:35:25 INFO - PID 4618 | 09:35:27 INFO - PID 4618 | Cycle 1(1): loaded http://localhost:59688/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59688/tests/kraken/driver.html?testName=audio-oscillator) 09:35:28 INFO - PID 4618 | RSS: Main: 157564928 09:35:28 INFO - PID 4618 | 09:35:30 INFO - PID 4618 | Cycle 1(1): loaded http://localhost:59688/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59688/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:35:30 INFO - PID 4618 | RSS: Main: 157511680 09:35:30 INFO - PID 4618 | 09:35:34 INFO - PID 4618 | Cycle 1(1): loaded http://localhost:59688/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59688/tests/kraken/driver.html?testName=imaging-darkroom) 09:35:34 INFO - PID 4618 | RSS: Main: 162119680 09:35:34 INFO - PID 4618 | 09:35:38 INFO - PID 4618 | Cycle 1(1): loaded http://localhost:59688/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59688/tests/kraken/driver.html?testName=imaging-desaturate) 09:35:38 INFO - PID 4618 | RSS: Main: 161939456 09:35:38 INFO - PID 4618 | 09:35:41 INFO - PID 4618 | Cycle 1(1): loaded http://localhost:59688/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59688/tests/kraken/driver.html?testName=json-parse-financial) 09:35:42 INFO - PID 4618 | RSS: Main: 162889728 09:35:42 INFO - PID 4618 | 09:35:44 INFO - PID 4618 | Cycle 1(1): loaded http://localhost:59688/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59688/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:35:44 INFO - PID 4618 | RSS: Main: 163631104 09:35:44 INFO - PID 4618 | 09:35:46 INFO - PID 4618 | Cycle 1(1): loaded http://localhost:59688/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59688/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:35:47 INFO - PID 4618 | RSS: Main: 163647488 09:35:47 INFO - PID 4618 | 09:35:49 INFO - PID 4618 | Cycle 1(1): loaded http://localhost:59688/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59688/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:35:49 INFO - PID 4618 | RSS: Main: 162947072 09:35:49 INFO - PID 4618 | 09:35:52 INFO - PID 4618 | Cycle 1(1): loaded http://localhost:59688/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59688/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:35:53 INFO - PID 4618 | RSS: Main: 162799616 09:35:53 INFO - PID 4618 | 09:35:56 INFO - PID 4618 | Cycle 1(1): loaded http://localhost:59688/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59688/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:35:57 INFO - PID 4618 | RSS: Main: 163426304 09:35:57 INFO - PID 4618 | 09:35:59 INFO - PID 4618 | Cycle 1(1): loaded http://localhost:59688/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59688/tests/kraken/driver.html?testName=ai-astar) 09:35:59 INFO - PID 4618 | RSS: Main: 164859904 09:35:59 INFO - PID 4618 | 09:35:59 INFO - PID 4618 | __start_tp_report 09:35:59 INFO - PID 4618 | _x_x_mozilla_page_load 09:35:59 INFO - PID 4618 | _x_x_mozilla_page_load_details 09:35:59 INFO - PID 4618 | |i|pagename|runs| 09:35:59 INFO - PID 4618 | |0;ai-astar;104;111;112;111;113;112;113;111;113;113 09:35:59 INFO - PID 4618 | |1;audio-beat-detection;154;127;128;182;126;126;129;129;149;133 09:35:59 INFO - PID 4618 | |2;audio-dft;163;165;164;164;165;166;175;165;163;162 09:35:59 INFO - PID 4618 | |3;audio-fft;76;75;77;119;76;76;76;75;95;75 09:35:59 INFO - PID 4618 | |4;audio-oscillator;85;84;107;84;84;84;97;84;85;102 09:35:59 INFO - PID 4618 | |5;imaging-gaussian-blur;114;114;115;114;114;114;115;114;117;114 09:35:59 INFO - PID 4618 | |6;imaging-darkroom;151;150;150;153;151;152;150;153;150;151 09:35:59 INFO - PID 4618 | |7;imaging-desaturate;94;95;95;95;94;95;95;95;95;95 09:35:59 INFO - PID 4618 | |8;json-parse-financial;53;54;54;53;54;53;57;55;56;54 09:35:59 INFO - PID 4618 | |9;json-stringify-tinderbox;56;56;55;54;55;55;55;57;55;56 09:35:59 INFO - PID 4618 | |10;stanford-crypto-aes;81;82;82;82;80;81;85;82;80;83 09:35:59 INFO - PID 4618 | |11;stanford-crypto-ccm;139;131;131;132;144;145;134;144;182;137 09:35:59 INFO - PID 4618 | |12;stanford-crypto-pbkdf2;188;203;195;195;207;191;196;195;188;192 09:35:59 INFO - PID 4618 | |13;stanford-crypto-sha256-iterative;75;73;74;76;72;78;72;72;74;75 09:35:59 INFO - PID 4618 | __end_tp_report 09:35:59 INFO - PID 4618 | __start_cc_report 09:35:59 INFO - PID 4618 | _x_x_mozilla_cycle_collect,837 09:35:59 INFO - PID 4618 | __end_cc_report 09:35:59 INFO - PID 4618 | __startTimestamp1494520559264__endTimestamp 09:35:59 INFO - PID 4618 | 09:35:59 INFO - PID 4618 | ------- Summary: start ------- 09:35:59 INFO - PID 4618 | Number of tests: 14 09:35:59 INFO - PID 4618 | 09:35:59 INFO - PID 4618 | [#0] ai-astar Cycles:10 Average:111.30 Median:112.50 stddev:2.71 (2.4%) stddev-sans-first:0.93 09:35:59 INFO - PID 4618 | Values: 104.0 111.0 112.0 111.0 113.0 112.0 113.0 111.0 113.0 113.0 09:35:59 INFO - PID 4618 | 09:35:59 INFO - PID 4618 | [#1] audio-beat-detection Cycles:10 Average:138.30 Median:131.00 stddev:18.28 (14.0%) stddev-sans-first:18.49 09:35:59 INFO - PID 4618 | Values: 154.0 127.0 128.0 182.0 126.0 126.0 129.0 129.0 149.0 133.0 09:35:59 INFO - PID 4618 | 09:35:59 INFO - PID 4618 | [#2] audio-dft Cycles:10 Average:165.20 Median:165.00 stddev:3.65 (2.2%) stddev-sans-first:3.78 09:35:59 INFO - PID 4618 | Values: 163.0 165.0 164.0 164.0 165.0 166.0 175.0 165.0 163.0 162.0 09:35:59 INFO - PID 4618 | 09:35:59 INFO - PID 4618 | [#3] audio-fft Cycles:10 Average:82.00 Median:76.00 stddev:14.35 (18.9%) stddev-sans-first:15.06 09:35:59 INFO - PID 4618 | Values: 76.0 75.0 77.0 119.0 76.0 76.0 76.0 75.0 95.0 75.0 09:35:59 INFO - PID 4618 | 09:35:59 INFO - PID 4618 | [#4] audio-oscillator Cycles:10 Average:89.60 Median:84.00 stddev:8.88 (10.6%) stddev-sans-first:9.27 09:35:59 INFO - PID 4618 | Values: 85.0 84.0 107.0 84.0 84.0 84.0 97.0 84.0 85.0 102.0 09:35:59 INFO - PID 4618 | 09:35:59 INFO - PID 4618 | [#5] imaging-gaussian-blur Cycles:10 Average:114.50 Median:114.00 stddev:0.97 (0.9%) stddev-sans-first:1.01 09:35:59 INFO - PID 4618 | Values: 114.0 114.0 115.0 114.0 114.0 114.0 115.0 114.0 117.0 114.0 09:35:59 INFO - PID 4618 | 09:35:59 INFO - PID 4618 | [#6] imaging-darkroom Cycles:10 Average:151.10 Median:151.00 stddev:1.20 (0.8%) stddev-sans-first:1.27 09:35:59 INFO - PID 4618 | Values: 151.0 150.0 150.0 153.0 151.0 152.0 150.0 153.0 150.0 151.0 09:35:59 INFO - PID 4618 | 09:35:59 INFO - PID 4618 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 09:35:59 INFO - PID 4618 | Values: 94.0 95.0 95.0 95.0 94.0 95.0 95.0 95.0 95.0 95.0 09:35:59 INFO - PID 4618 | 09:35:59 INFO - PID 4618 | [#8] json-parse-financial Cycles:10 Average:54.30 Median:54.00 stddev:1.34 (2.5%) stddev-sans-first:1.33 09:35:59 INFO - PID 4618 | Values: 53.0 54.0 54.0 53.0 54.0 53.0 57.0 55.0 56.0 54.0 09:35:59 INFO - PID 4618 | 09:35:59 INFO - PID 4618 | [#9] json-stringify-tinderbox Cycles:10 Average:55.40 Median:55.50 stddev:0.84 (1.5%) stddev-sans-first:0.87 09:35:59 INFO - PID 4618 | Values: 56.0 56.0 55.0 54.0 55.0 55.0 55.0 57.0 55.0 56.0 09:35:59 INFO - PID 4618 | 09:35:59 INFO - PID 4618 | [#10] stanford-crypto-aes Cycles:10 Average:81.80 Median:82.00 stddev:1.48 (1.8%) stddev-sans-first:1.54 09:35:59 INFO - PID 4618 | Values: 81.0 82.0 82.0 82.0 80.0 81.0 85.0 82.0 80.0 83.0 09:35:59 INFO - PID 4618 | 09:35:59 INFO - PID 4618 | [#11] stanford-crypto-ccm Cycles:10 Average:141.90 Median:141.50 stddev:15.12 (10.7%) stddev-sans-first:16.00 09:35:59 INFO - PID 4618 | Values: 139.0 131.0 131.0 132.0 144.0 145.0 134.0 144.0 182.0 137.0 09:35:59 INFO - PID 4618 | 09:35:59 INFO - PID 4618 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:195.00 Median:195.00 stddev:6.07 (3.1%) stddev-sans-first:5.89 09:35:59 INFO - PID 4618 | Values: 188.0 203.0 195.0 195.0 207.0 191.0 196.0 195.0 188.0 192.0 09:35:59 INFO - PID 4618 | 09:35:59 INFO - PID 4618 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.10 Median:74.50 stddev:1.97 (2.6%) stddev-sans-first:2.06 09:35:59 INFO - PID 4618 | Values: 75.0 73.0 74.0 76.0 72.0 78.0 72.0 72.0 74.0 75.0 09:35:59 INFO - PID 4618 | -------- Summary: end -------- 09:35:59 INFO - PID 4618 | 09:36:00 INFO - TEST-INFO | 4618: exit 0 09:36:00 INFO - TEST-OK | kraken | took 63296ms 09:36:00 INFO - SUITE-END | took 1182s 09:36:00 INFO - Completed test suite (00:19:42) 09:36:00 INFO - javascript benchmark 09:36:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6782.909208824976, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2753.0777189641467, 2758.3104223944015, 2773.2373597260753, 2787.8606069696516, 2796.650418697664, 5117.232415137732, 5245.475452454757, 5246.1868410396355, 5258.710770907599, 5262.210668399737, 5178.715170665612, 5226.55574276187, 5272.762725677344, 5279.155335146377, 5339.439358867321, 4671.9766401168035, 4674.90650186996, 4686.320483529886, 4692.178868697982, 4738.881527961794, 4713.0, 4738.312944623028, 4738.715677059387, 4754.38193034905, 4771.522847715218, 4566.086782643451, 4623.214053610895, 4646.628269738413, 4696.976515117437, 4702.5297470253045, 4640.071985602893, 4650.232711602582, 4667.276572131291, 4705.55297246761, 4715.693479923794, 4594.173048851206, 4637.675362724611, 4646.976765116153, 4651.5813576778255, 4675.251959686434, 5150.407703114152, 5153.252778347156, 5202.817901373433, 5227.8431647050265, 5228.163493840967, 5626.324841019104, 5638.548916086703, 5638.577106716972, 5645.491905728504, 5654.293213348332, 5624.71876406178, 5625.634333768292, 5653.773849046033, 5672.9505041567445, 5689.9146512802345, 5162.793488260428, 5230.503102205323, 5235.65968212065, 5240.502152295526, 5244.763985620656, 5733.226014488074, 5791.494211504954, 5814.040683287295, 5829.329627092854, 5844.766209351664, 5488.890222195613, 5552.611317207838, 5573.526250268692, 5592.3009623797025, 5598.776048958078, 5620.775168993195, 5728.971355143281, 5733.655980641175, 5734.627249228787, 5743.8564035899435, 5601.5238704709745, 5616.1014237721765, 5621.0444224481935, 5626.043572592669, 5638.097904335286, 5462.426445223274, 5602.327720673546, 5605.971970140205, 5606.523445507178, 5607.635503692247, 4618.030213655103, 4621.792019359137, 4626.4910859805395, 4627.5835174833755, 4644.373009643722, 4019.959800402017, 4038.5759495253164, 4062.3500239961463, 4073.7759423232005, 4124.484439445069, 4877.6829506081995, 4918.163912134945, 4918.262260660929, 4936.456989731127, 4983.825566105242, 5705.857353566194, 5709.457601527848, 5715.371309155989, 5733.426657334317, 5739.598228124075, 4842.225243960949, 4897.632677549198, 4908.067467181224, 4917.950820491821, 4928.162212423825, 4851.102546028926, 4958.677685950402, 4965.255211718199, 4966.6771659842, 4972.701637901738, 5241.344831896013, 5286.074936886106, 5286.7356632169, 5289.259503669567, 5310.9734451327495, 5599.04816181242, 5702.116171993348, 5716.742746576331, 5748.597598168088, 5775.306963164448, 5243.108671525773, 5305.761240744098, 5374.785008599769, 5379.515843574097, 5403.648762830403, 5597.384287728428, 5646.661200327829, 5671.631343962629, 5700.543956483389, 5701.828945131652, 5613.971930140323, 5698.715064246689, 5739.339975902824, 5747.597668163188, 5750.856228594319, 5476.863078423071, 5477.1510415885605, 5524.447555244444, 5544.916826247691, 5546.805861794979, 5495.752691128829, 5526.309211348582, 5539.723013849372, 5541.141123125922, 5542.057850165401, 5307.9734601326745, 5403.675779453245, 5434.1033729435785, 5434.239206511171, 5493.176023596492, 5510.201020852034, 5510.531604813636, 5519.31008623922, 5536.91694624589, 5540.307461567304, 5331.280277162688, 5379.757910893941, 5383.623146379872, 5383.703896285742, 5394.9460505394445], "value": 5134.875797326774, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [629.2323365494099, 705.9611721355326, 724.5073350121917, 1142.6000899685112, 1193.8686744458103, 33456.83271583642, 34102.317953640915, 34132.1466963326, 34137.82931085344, 34270.48594271088, 32126.0, 32458.0, 32458.02625921226, 32645.18387040319, 32686.0, 6519.511036672245, 6656.534042617019, 6866.896996545056, 7010.6494675266285, 7040.119985001875, 6874.759383421581, 6977.546459480143, 7017.438604911595, 7018.859622807541, 7034.718611255569, 6904.4131248843905, 7003.754868579601, 7035.331643493885, 7056.894146587805, 7060.646967651623, 6973.616451095188, 7003.47473939454, 7004.5797252164775, 7042.119735033121, 7047.647617619098, 6868.759593414256, 6883.862322753517, 6930.896036559456, 6958.0954475918315, 6959.826004349881, 31266.843665781755, 31601.52597711013, 31623.525647115082, 31726.0, 31805.204869878205, 3057.1745628680133, 3103.6275646922513, 3113.050519591524, 3117.8285194314303, 3123.0, 24396.390090247707, 24735.505289894103, 24763.62854557183, 24800.627990580157, 24810.627840582405, 3100.3644252928098, 3162.7153556180056, 3189.6012998375204, 3196.5844440222854, 3218.678132186786, 142799.0, 143360.0, 144284.27857860748, 149212.0, 153010.0, 39629.80185099056, 39700.0, 39837.80081099634, 39984.0, 40151.19697606032, 41163.0, 41588.79205603953, 41647.0, 41890.37164442536, 44142.33786493205, 12661.050421218446, 12691.873081269254, 12702.682432939066, 12771.042171837149, 12778.744425111445, 10080.143187829117, 10146.239032072624, 10170.796584068277, 10190.796184076276, 10230.744231394274, 12887.09790314669, 12903.032272579407, 12903.161294516014, 12921.612351629467, 13069.215847049018, 5298.629095963245, 5330.227117068003, 5389.649672771257, 5474.124140137559, 5478.342598888159, 744.9106107267054, 817.1787353709484, 823.8681810910346, 835.4736515994884, 852.7527017165077, 19892.45182118851, 21283.680744788842, 21333.78666213349, 21454.46363840885, 21678.0, 894.695803426838, 898.874157617934, 899.3434792601438, 901.7475106970059, 926.4719110107309, 10513.84229236562, 10738.516766745515, 10918.07196388316, 11107.888921110847, 11173.608923687794, 173591.13204433897, 174993.0, 175088.0, 175212.0, 175340.0, 40638.59361406407, 40651.79674101611, 40833.59166408298, 40990.59009409927, 41059.1788164241, 1211.4669545399995, 1228.225214564963, 1234.2039384597065, 1237.5854088880146, 1237.789575772121, 18885.71671424957, 19328.22687092557, 20463.18147274152, 20465.897670511553, 20615.8969205159, 30202.395952080395, 37550.0, 37706.2458750818, 37862.242755144194, 38120.0, 158047.0, 159193.2040339791, 159814.0, 160016.0, 160072.0, 30390.848045760515, 30868.845655772475, 31008.58905520436, 31099.378012440076, 31123.688763112987, 36447.81776091103, 37445.0, 37540.2491950154, 37831.62168378281, 37941.0, 27572.44855102927, 27839.860800695868, 27998.0, 28430.0, 29108.56337154902, 27500.724992749816, 27562.724372756817, 28275.293117672223, 28811.855940721, 28977.7102228975, 30666.0, 30754.384912301182, 31032.689673103883, 31040.689593104682, 31066.0, 26795.598066028637, 27027.189184324503, 27452.313692157855, 27675.308117297227, 28442.715572844834, 20048.0, 22797.658035129156, 22826.54346913086, 22971.88514057419, 22987.770122299233], "value": 14591.757139879875, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [412.21267379305533, 432.58687953004886, 436.1255682356876, 437.67611967144325, 451.42668810610525, 17003.0, 17075.82924170758, 17308.307667693276, 17426.124689426244, 17720.379786707468, 15618.76571851423, 15764.76352854708, 15802.367905283803, 15857.207139643, 16438.342466301365, 5362.069654924224, 5366.382865970405, 5447.264619276387, 5525.336959564837, 5534.197541356501, 5318.601104917147, 5393.919091213635, 5442.727863606824, 5486.149646804752, 5545.057340252147, 5502.284702988606, 5505.697186654733, 5511.944880551203, 5534.75093620786, 5536.944630553703, 5371.0, 5445.891082178354, 5459.836204913859, 5467.097928841737, 5511.173324001392, 4962.106820772259, 4971.850844474636, 4972.229304457815, 4991.401031876162, 5000.874978125539, 21376.572468550698, 22360.776392236032, 22470.213542525933, 22644.88677556618, 22943.082276708912, 1600.8719302455893, 1627.1213544685975, 1627.7476991066403, 1631.5023917705096, 1635.9366411832286, 14640.267986600627, 14734.852651473455, 14767.630809229855, 14920.403183872631, 14985.700285994328, 2050.559129787088, 2120.724305840231, 2132.637451633226, 2159.0392275437434, 2174.1086154676505, 77206.0, 86937.35127677016, 88143.0, 98595.50702246514, 98921.50539247258, 10358.53386597605, 10412.32319899204, 10498.0, 10591.417472039111, 10628.787424251472, 15293.694126117638, 15301.08193508393, 15309.0, 15340.232988350537, 15361.385544578316, 7357.448191385667, 7589.924100759032, 7663.233676632292, 7739.110002349689, 7796.337311328601, 6855.108835851407, 6901.827454313682, 6990.6155161465595, 7055.082839230867, 7340.0457940467395, 6540.117084193668, 6612.702428390734, 6764.830879228059, 6765.729370825112, 6825.918094981079, 1902.3627084926427, 1909.627622613577, 1925.5186203449139, 1925.8844469331605, 2034.9694754578693, 836.4395854777314, 869.7909905231733, 886.0652012127139, 903.9864402033975, 907.6142639378238, 17184.742228866577, 17320.393786217675, 17644.382446614305, 17726.556836079202, 19831.206751730057, 872.2367928062945, 879.1955360844755, 882.1134759566722, 883.1257055514994, 906.3157316226207, 9198.0, 9210.401323914059, 11091.826839396243, 12019.158658893968, 12077.15459917815, 344530.0, 349262.0, 353754.0, 358668.0, 360103.0, 76761.0, 81428.59285703534, 82035.0, 82083.17916820875, 82101.17898820936, 1069.6202847989007, 1084.1760262200785, 1129.8587676540433, 1132.9546818127346, 1138.2828817844704, 17097.48707538776, 17212.913935430744, 17884.821151788317, 18491.260349585868, 18690.719639205152, 37967.0, 38354.232915342094, 38919.6108038916, 38951.22097557976, 39379.0, 49515.75242123767, 49521.75239123782, 49678.0, 49704.50295496999, 50789.0, 8238.546879921421, 8278.503289802631, 8280.420370574002, 8622.568871556523, 8629.266512346521, 12785.488580456678, 13122.868771312547, 13127.737445251232, 13224.867751322749, 13369.0, 11328.546858125583, 11374.94312528432, 11402.0, 11408.600698975495, 11570.132240081859, 9453.101955314234, 9476.620935162517, 9762.267829912644, 10593.576256949636, 10786.946065269623, 11184.496697648463, 11334.60328888485, 11343.829842552534, 11477.655670329903, 11612.94193529027, 8260.421770476009, 8323.084460709197, 8371.539565323965, 8448.619812108347, 8467.449615774955, 11310.491027903929, 11484.712882177679, 11527.250728702942, 11532.59635912739, 11763.647090587296], "value": 8881.800497091564, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1264.1846009323986, 1316.1510825517541, 1326.542342891702, 1329.9534516291926, 1334.6062911441127, 9867.309288349818, 10292.742681432968, 10294.073533381994, 10424.270301078928, 10639.202059845504, 10429.374237545753, 10494.632687855927, 10570.735731606714, 10599.152067834573, 10615.522301496432, 2263.1739415113448, 2291.0606651272983, 2303.4221558232666, 2307.261676263596, 2311.838171328008, 2187.5843589717983, 2239.428945618865, 2243.2372993182316, 2244.652078927769, 2245.236619549353, 2255.7857003584713, 2265.3543740034092, 2276.4422716434497, 2299.9195028174017, 2312.8843557822124, 2138.6791981202946, 2222.911083556664, 2238.328501449567, 2245.292732789166, 2247.1910112359537, 2106.6734656128324, 2192.7697591752794, 2218.035154707691, 2218.4564781628605, 2240.955180896373, 10211.336263142872, 10217.0, 10272.948635256776, 10278.7430314242, 10301.072903438726, 5726.455986681259, 5891.499222566087, 5929.466348028655, 5938.8218353449465, 5950.226470558843, 3323.202431416467, 3472.58329000519, 3490.633483484223, 3548.9112772180642, 3556.928861422757, 1093.704699731068, 1140.749035212252, 1146.8222425523973, 1154.7344110854467, 1161.4134861894775, 15179.0, 15377.0, 15416.691666166726, 15431.537053888402, 15536.456224032105, 4750.0, 4904.362432883702, 5129.666571672829, 5163.612729045337, 5164.92252616211, 10979.341239525655, 10995.065419439277, 11011.944940275247, 11031.83452248217, 11037.227394082338, 5597.832065038056, 5613.522850557748, 5615.6630602164005, 5617.747201375948, 5628.296462942132, 5172.482751724798, 5173.094708426011, 5194.168932970707, 5319.228711836762, 5409.891802163935, 3811.8856434306463, 3970.642642162219, 4006.939895901564, 4043.555208927016, 4069.5320038195987, 1150.3672979861044, 1150.5800382860364, 1173.2549830857297, 1174.1135442740847, 1174.9118816088658, 690.8963655451722, 720.8846584546448, 723.1430754555868, 734.1043926409878, 743.4387037786545, 5585.804496842591, 5665.915011274834, 5733.31200255972, 5763.682997435181, 5787.363390027093, 527.2907938822278, 532.7815595605783, 540.3893600231713, 549.6784381136999, 550.1005855426326, 3523.8061906595385, 3527.4532447470683, 3562.87529936451, 3586.5875424326014, 3726.7018638508853, 13282.468701251843, 13533.796993045113, 13735.175889446666, 13798.793018104736, 13810.516631917835, 13093.541726039542, 13548.0, 13608.795868061987, 13667.63323667645, 13709.588712338445, 7599.430042746816, 7612.695492180251, 7682.73110441143, 7702.306792388712, 7716.922830771733, 5719.685417302088, 5773.566982476247, 5798.478136967693, 5861.413858614104, 5962.2249107615735, 12112.697182570506, 12115.212511186744, 12118.515259389525, 12224.083193760325, 12372.752544948871, 12188.20776649515, 12506.18709783861, 12636.178648387826, 12726.300053497147, 12827.102102853083, 9527.142557169887, 9677.370970887152, 9779.26655500826, 9794.26543009263, 9819.0, 9451.763705907408, 9496.477693726634, 9551.283653725859, 9563.187129094105, 9585.760355991157, 8334.833303333779, 8368.20502052304, 8536.103709110703, 8582.184692454206, 8610.61252243638, 8239.588020599065, 8241.546714930737, 8340.582970851554, 8434.578271086544, 8545.401821872409, 7587.393008559192, 7711.9614401929875, 7723.729669461542, 7727.729529466442, 7737.845243095219, 5446.645968012067, 5459.317585301837, 5530.889382212414, 5568.888622227614, 5674.526708913572, 5699.34457537372, 5714.942850571441, 5743.8851222976145, 5846.766129354778, 5925.851853703692], "value": 4813.7355165851195, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [392.90373858404695, 397.61431411530816, 398.34472293077886, 401.1275475840048, 409.27762499188935, 5511.421300763417, 5550.472705093015, 5557.666540007603, 5567.8886422271535, 5574.9721251393685, 5304.973475132619, 5318.937009442602, 5393.892122157555, 5419.078756611376, 5420.430854760252, 4248.150369926012, 4255.9787201063955, 4256.2338779019765, 4262.957370426287, 4268.40242366069, 4260.169266992938, 4261.360795880627, 4262.208253593901, 4284.100338928829, 4291.163223171483, 4136.66906647469, 4194.832206711728, 4246.341817018367, 4252.702310838243, 4254.87235382939, 4218.704690671654, 4220.78896055199, 4226.704130710851, 4235.0, 4266.87199384019, 3828.755654412327, 3854.075021994729, 3854.1906199698096, 3870.651641352292, 3878.26313000529, 5177.197534382177, 5329.680219186823, 5344.732763361816, 5352.2506849041165, 5354.089804733166, 4980.850574482735, 4987.850364489072, 4996.1756310208775, 4996.525330093636, 4999.100161970844, 5730.0, 5733.0, 5741.368449470555, 5747.0, 5749.396313387076, 4234.87295381139, 4258.318669012943, 4298.548652391485, 4307.0, 4337.327714204303, 5857.355690874, 6005.279366476007, 6013.75944962201, 6021.548383871227, 6035.758569657209, 4072.6945479089186, 4094.242565125461, 4094.6724262058965, 4105.486814148231, 4108.732932359387, 4167.312393455107, 4204.768737719455, 4206.978965105216, 4207.537170911197, 4209.400427837463, 4730.432348118248, 4748.0978614063215, 4822.638302127354, 4828.734419606885, 4829.855104346875, 5708.343540492813, 5716.714164291852, 5733.655980641175, 5743.913841292384, 5766.0, 1411.6329754263966, 1423.2172305232082, 1424.722179175051, 1425.7026106243477, 1429.685469196775, 911.5123408976299, 944.7543638653999, 960.3325688646323, 973.493783232715, 978.1245785022365, 5093.733288686573, 5094.2358646203365, 5101.566366858859, 5112.376385975472, 5123.359580052494, 5940.970295148497, 5966.731497082555, 5971.5222782177325, 6027.60820546663, 6035.637861728311, 4897.706137631753, 5051.4695956924725, 5084.923726144111, 5103.464136265713, 5130.563902068291, 3158.8104713717253, 3193.0101668482844, 3194.7923384979904, 3201.2477067889026, 3204.9359012819614, 5473.534749546333, 5611.775528978795, 5624.29696287964, 5637.7181140942785, 5639.718014099279, 6164.520515076439, 6168.938310616836, 6168.938310616926, 6174.5060395168275, 6175.783847565403, 5538.889222215533, 5571.832845014656, 5598.692071936082, 5656.123300888369, 5670.8941756357935, 5851.502622277154, 5938.901303258911, 6011.81964541047, 6020.819375418744, 6032.642655402499, 5845.866258815587, 5848.298204215522, 5877.206577112035, 5894.0274854648505, 5910.911336330045, 5446.428125046813, 5593.524550413261, 5628.9471580462005, 5632.690202038763, 5636.718164091697, 4124.814383352817, 4128.483939507561, 4130.421740956329, 4143.81352839117, 4147.2534943710425, 5892.587518873637, 6017.518598512021, 6035.517158627387, 6039.245094363205, 6039.278805540575, 5747.971260143672, 5769.53843692512, 5772.538196944319, 5781.219535362672, 5813.680247586423, 5772.855678607901, 5777.162311464898, 5792.4497172768515, 5793.536517078708, 5828.912566311424, 5525.723713814374, 5709.0, 5719.685417302088, 5721.5136713379825, 5733.254676891977, 5829.59192856496, 5836.708164591839, 5847.649141051551, 5860.882782344414, 5865.296164460292, 5545.473180047889, 5666.405027472221, 5667.971660141838, 5684.175794509855, 5687.516561092353], "value": 4365.027957836139, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [711.4664001998501, 726.0923845193508, 732.6226993098555, 758.7003133762164, 775.0621747685299, 12517.248965062103, 13129.0, 13653.522126725567, 14580.343884525182, 14660.853391466095, 14396.208208548553, 14539.49111781088, 14894.404223831061, 14933.775993360108, 15035.924820375883, 881.3389957531836, 900.6442455230181, 905.0135352465811, 905.5427009360296, 906.3383729877194, 913.2967614936495, 914.9451032938011, 915.50562696144, 915.922146617535, 919.5218486387075, 955.961761529538, 958.324381311174, 959.7744530035436, 965.3580369054571, 966.8839739231267, 946.0397696338222, 949.715085474355, 950.396498223633, 952.3190918493276, 954.804265125651, 696.9163700355939, 697.6372286411089, 701.5860642221063, 702.6065403374075, 704.9330313620197, 14321.140731556035, 14340.56978290653, 14855.2572371381, 15409.768853467207, 15530.378784848592, 15688.921555392264, 16014.759778603331, 16217.432389866415, 16705.415310464195, 16787.0767107809, 10602.469876506144, 11081.390523521202, 11140.275882067639, 12407.565735199225, 12562.05784566152, 2926.1514160893316, 2951.719586639287, 2955.689652586469, 2955.881764729408, 2956.9556456653168, 66491.33508664949, 66654.66672666655, 66949.3305066948, 67203.3279667202, 68880.6555967222, 12065.336406497727, 12210.877891221151, 12354.25874447536, 12365.938170309271, 12411.193272437262, 21683.891580542313, 21747.0, 22545.661815072788, 22909.0, 22918.65622015671, 5697.145428185805, 5752.33848107473, 5801.970990145107, 5813.7383817728305, 6534.4445722113205, 2974.256435891027, 3048.9847550762106, 3058.403611295776, 3076.2770748873995, 3093.1648454917045, 3541.327147841902, 3609.242059167552, 3643.744937854378, 3664.340418724602, 3811.942820857689, 780.8282177920848, 795.073739093949, 796.8246985663145, 800.087899794235, 803.333233416263, 604.1813342920279, 616.6269407008696, 625.7622103600603, 627.6673363117555, 648.0538413915642, 9623.326367154232, 10301.74245643865, 10535.104516116216, 10653.307535010199, 10959.726006849893, 576.8442520519436, 578.4735890339771, 583.2301362201853, 584.476893180601, 584.5995493087249, 4459.710118842265, 4546.4544254689645, 4549.681522293407, 4567.132244873464, 4577.908441831211, 58124.418755812745, 58791.41208587945, 59741.701291493264, 60751.0, 63156.68421657954, 50730.23904641433, 51408.74295628498, 51858.22212666813, 52251.0, 52592.0, 47941.5205847944, 52367.73816130895, 53658.73170634122, 53707.462925370244, 53766.73116634392, 9897.25770567196, 10365.0, 10917.890821092005, 10970.0, 11275.267107637977, 27201.0, 27261.72738272592, 27952.44095118127, 28382.432351352443, 28686.0, 32773.0, 33128.0, 33269.0, 33451.83274083614, 33613.66386336106, 13967.022308438718, 14164.575062748134, 14189.361478733688, 14287.785683214968, 14372.35324410425, 16223.837761622233, 16527.091009994565, 16630.0, 17016.914915425343, 17309.22108505095, 14356.138631682132, 15565.210000850047, 15832.0, 15915.761263580824, 16066.43767468133, 15207.923960380569, 15325.080495170325, 15488.922555387151, 15513.37946482128, 15567.688646226785, 14297.428102875769, 14454.132752034911, 15255.771163432779, 15716.528504144893, 15870.761938571162, 10456.68629941103, 11134.88865111371, 11181.776364472502, 11549.769004620028, 11750.29498230109, 12137.635870923887, 12925.870741292843, 13007.739845202854, 13160.868391316348, 13163.0], "value": 6963.973023351553, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1549.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 111.0, 112.0, 111.0, 113.0, 112.0, 113.0, 111.0, 113.0, 113.0], "value": 111.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 127.0, 128.0, 182.0, 126.0, 126.0, 129.0, 129.0, 149.0, 133.0], "value": 138.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 165.0, 164.0, 164.0, 165.0, 166.0, 175.0, 165.0, 163.0, 162.0], "value": 165.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 77.0, 119.0, 76.0, 76.0, 76.0, 75.0, 95.0, 75.0], "value": 82.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 107.0, 84.0, 84.0, 84.0, 97.0, 84.0, 85.0, 102.0], "value": 89.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 115.0, 114.0, 114.0, 114.0, 115.0, 114.0, 117.0, 114.0], "value": 114.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 150.0, 150.0, 153.0, 151.0, 152.0, 150.0, 153.0, 150.0, 151.0], "value": 151.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 54.0, 54.0, 53.0, 54.0, 53.0, 57.0, 55.0, 56.0, 54.0], "value": 54.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 55.0, 54.0, 55.0, 55.0, 55.0, 57.0, 55.0, 56.0], "value": 55.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 82.0, 82.0, 80.0, 81.0, 85.0, 82.0, 80.0, 83.0], "value": 81.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 131.0, 131.0, 132.0, 144.0, 145.0, 134.0, 144.0, 182.0, 137.0], "value": 141.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 203.0, 195.0, 195.0, 207.0, 191.0, 196.0, 195.0, 188.0, 192.0], "value": 195.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 73.0, 74.0, 76.0, 72.0, 78.0, 72.0, 72.0, 74.0, 75.0], "value": 74.1, "unit": "score"}]}]} 09:36:00 INFO - Return code: 0 09:36:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:36:01 INFO - # TBPL SUCCESS # 09:36:01 INFO - Running post-action listener: _resource_record_post_action 09:36:01 INFO - [mozharness: 2017-05-11 16:36:01.045885Z] Finished run-tests step (success) 09:36:01 INFO - Running post-run listener: _resource_record_post_run 09:36:01 INFO - Total resource usage - Wall time: 1199s; CPU: 13.0%; Read bytes: 8192; Write bytes: 255401984; Read time: 152; Write time: 118116 09:36:01 INFO - TinderboxPrint: CPU usage
13.3% 09:36:01 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 152 09:36:01 INFO - TinderboxPrint: I/O write bytes / time
255,401,984 / 118,116 09:36:01 INFO - TinderboxPrint: CPU idle
8,296.7 (86.7%) 09:36:01 INFO - TinderboxPrint: CPU user
1,215.8 (12.7%) 09:36:01 INFO - TinderboxPrint: Swap in / out
0 / 0 09:36:01 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 479232; Read time: 0; Write time: 56 09:36:01 INFO - run-tests - Wall time: 1183s; CPU: 13.0%; Read bytes: 4096; Write bytes: 254607360; Read time: 136; Write time: 118056 09:36:01 INFO - Running post-run listener: _upload_blobber_files 09:36:01 INFO - Blob upload gear active. 09:36:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:36:01 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 09:36:01 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'] 09:36:01 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 09:36:02 INFO - (blobuploader) - INFO - Open directory for files ... 09:36:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:36:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:36:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:36:03 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:36:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:36:03 INFO - (blobuploader) - INFO - Done attempting. 09:36:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:36:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:36:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:36:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:36:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:36:03 INFO - (blobuploader) - INFO - Done attempting. 09:36:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:36:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:36:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:36:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:36:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:36:08 INFO - (blobuploader) - INFO - Done attempting. 09:36:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:36:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:36:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:36:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:36:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:36:09 INFO - (blobuploader) - INFO - Done attempting. 09:36:09 INFO - (blobuploader) - INFO - Iteration through files over. 09:36:09 INFO - Return code: 0 09:36:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:36:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:36:09 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d99e930f7d0410a0d36dfaaae8da01c22dd353256937eca0f7572064b4232124da0b9b7cf76adb051b3d8d61d9ae6e76dc93174c933177bd51b25e3b86995020", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2e38b57ffabe5252dd93f6337dc8ece3a5c27621d63a4bb21b090bf8085c2735a4fe89a8d10a62d1cd509e8aa6120f8d923a5564b4b95d35b2cc7166cc449512", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e94e0b73dc471d084b6e20c65743f14b8a1d5427dc059a85bb9ac2e8fcf560f1509436c8c8b5334000cb17589b905f8e20ee74aed025eefeea044441750fc9ca", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/47f342d4ffd06edcb794e8ef70f9213bbe7f3cce5b49ebe41a5c9c074fa71ea3cdf7307258ffe78f1fe9e5ccd304aa81f3304a57cfab0e11eedc8b1a1c2f3c13"} 09:36:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:36:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:36:09 INFO - Contents: 09:36:09 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d99e930f7d0410a0d36dfaaae8da01c22dd353256937eca0f7572064b4232124da0b9b7cf76adb051b3d8d61d9ae6e76dc93174c933177bd51b25e3b86995020", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2e38b57ffabe5252dd93f6337dc8ece3a5c27621d63a4bb21b090bf8085c2735a4fe89a8d10a62d1cd509e8aa6120f8d923a5564b4b95d35b2cc7166cc449512", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e94e0b73dc471d084b6e20c65743f14b8a1d5427dc059a85bb9ac2e8fcf560f1509436c8c8b5334000cb17589b905f8e20ee74aed025eefeea044441750fc9ca", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/47f342d4ffd06edcb794e8ef70f9213bbe7f3cce5b49ebe41a5c9c074fa71ea3cdf7307258ffe78f1fe9e5ccd304aa81f3304a57cfab0e11eedc8b1a1c2f3c13"} 09:36:09 INFO - Running post-run listener: copy_logs_to_upload_dir 09:36:09 INFO - Copying logs to upload dir... 09:36:09 INFO - mkdir: /builds/slave/test/build/upload/logs 09:36:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=2263.575888 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 37 mins, 43 secs) (at 2017-05-11 09:36:09.034860) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-11 09:36:09.038866) ========= 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=a0d2d2de893abaa8702a9954000001a7-1494518303.374353-1973210711 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d99e930f7d0410a0d36dfaaae8da01c22dd353256937eca0f7572064b4232124da0b9b7cf76adb051b3d8d61d9ae6e76dc93174c933177bd51b25e3b86995020", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2e38b57ffabe5252dd93f6337dc8ece3a5c27621d63a4bb21b090bf8085c2735a4fe89a8d10a62d1cd509e8aa6120f8d923a5564b4b95d35b2cc7166cc449512", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e94e0b73dc471d084b6e20c65743f14b8a1d5427dc059a85bb9ac2e8fcf560f1509436c8c8b5334000cb17589b905f8e20ee74aed025eefeea044441750fc9ca", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/47f342d4ffd06edcb794e8ef70f9213bbe7f3cce5b49ebe41a5c9c074fa71ea3cdf7307258ffe78f1fe9e5ccd304aa81f3304a57cfab0e11eedc8b1a1c2f3c13"} build_url:https://queue.taskcluster.net/v1/task/VqHjFaJWSBWRUdUhiRa35Q/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011251 build_url: 'https://queue.taskcluster.net/v1/task/VqHjFaJWSBWRUdUhiRa35Q/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d99e930f7d0410a0d36dfaaae8da01c22dd353256937eca0f7572064b4232124da0b9b7cf76adb051b3d8d61d9ae6e76dc93174c933177bd51b25e3b86995020", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2e38b57ffabe5252dd93f6337dc8ece3a5c27621d63a4bb21b090bf8085c2735a4fe89a8d10a62d1cd509e8aa6120f8d923a5564b4b95d35b2cc7166cc449512", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e94e0b73dc471d084b6e20c65743f14b8a1d5427dc059a85bb9ac2e8fcf560f1509436c8c8b5334000cb17589b905f8e20ee74aed025eefeea044441750fc9ca", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/47f342d4ffd06edcb794e8ef70f9213bbe7f3cce5b49ebe41a5c9c074fa71ea3cdf7307258ffe78f1fe9e5ccd304aa81f3304a57cfab0e11eedc8b1a1c2f3c13"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-11 09:36:09.065593) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 09:36:09.065926) ========= 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=a0d2d2de893abaa8702a9954000001a7-1494518303.374353-1973210711 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004541 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 09:36:09.118351) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-11 09:36:09.118659) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-11 09:36:09.118923) ========= ========= Total master_lag: 0.13 =========